Thu Nov 11 21:01:05 UTC 2021 Description="'Webradio kein Logo und kein Track-Info '" # 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="b553157ab0ed8a10e18174f1d1fbb16bce4a88a2" VOLUMIO_FE_VERSION="17a64720dbaa388709b7c5e921d042119e1b9cfc" VOLUMIO_FE3_VERSION="9c2b4869bb4a74f513d21b75a15de444934231c5" VOLUMIO_BE_VERSION="842ade1e5148b798d9a3c8b966b6e96e86dbdca7" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumiobuster" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 11 Nov 2021 02:58:13 PM CET" VOLUMIO_VERSION="3.139" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="99bc010d47700474a93e6d47325598a4" # ifconfig --------------- eth0: flags=4163 mtu 1500 inet 192.168.178.172 netmask 255.255.255.0 broadcast 192.168.178.255 ether b8:27:eb:43:bd:38 txqueuelen 1000 (Ethernet) RX packets 5607 bytes 4669694 (4.4 MiB) RX errors 0 dropped 0 overruns 0 frame 0 TX packets 5075 bytes 974112 (951.2 KiB) TX errors 0 dropped 0 overruns 0 carrier 0 collisions 0 lo: flags=73 mtu 65536 inet 127.0.0.1 netmask 255.0.0.0 loop txqueuelen 1000 (Local Loopback) RX packets 1585 bytes 233053 (227.5 KiB) RX errors 0 dropped 0 overruns 0 frame 0 TX packets 1585 bytes 233053 (227.5 KiB) TX errors 0 dropped 0 overruns 0 carrier 0 collisions 0 wlan0: flags=4163 mtu 1500 inet 192.168.211.1 netmask 255.255.255.0 broadcast 192.168.211.255 ether 3c:49:37:17:69:71 txqueuelen 1000 (Ethernet) RX packets 0 bytes 0 (0.0 B) RX errors 0 dropped 0 overruns 0 frame 0 TX packets 0 bytes 0 (0.0 B) TX errors 0 dropped 33 overruns 0 carrier 0 collisions 0 # sudo iwconfig --------------- eth0 no wireless extensions. lo no wireless extensions. wlan0 IEEE 802.11bg ESSID:"Volumio" Nickname:"" Mode:Master Frequency:2.427 GHz Access Point: 3C:49:37:17:69:71 Bit Rate:54 Mb/s Sensitivity:0/0 Retry:off RTS thr:off Fragment thr:off Encryption key:off Power Management:off Link Quality:0 Signal level:0 Noise level:0 Rx invalid nwid:0 Rx invalid crypt:0 Rx invalid frag:0 Tx excessive retries:0 Invalid misc:0 Missed beacon:0 # aplay -l --------------- xcb_connection_has_error() returned true **** 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: sndrpihifiberry [snd_rpi_hifiberry_dacplus], device 0: HiFiBerry DAC+ Pro HiFi pcm512x-hifi-0 [HiFiBerry DAC+ Pro HiFi pcm512x-hifi-0] Subdevices: 0/1 Subdevice #0: subdevice #0 # ps -ef --------------- UID PID PPID C STIME TTY TIME CMD root 1 0 3 20:55 ? 00:00:12 /sbin/init root 2 0 0 20:55 ? 00:00:00 [kthreadd] root 3 2 0 20:55 ? 00:00:00 [rcu_gp] root 4 2 0 20:55 ? 00:00:00 [rcu_par_gp] root 8 2 0 20:55 ? 00:00:00 [mm_percpu_wq] root 9 2 0 20:55 ? 00:00:00 [ksoftirqd/0] root 10 2 0 20:55 ? 00:00:00 [rcu_sched] root 11 2 0 20:55 ? 00:00:00 [migration/0] root 12 2 0 20:55 ? 00:00:00 [cpuhp/0] root 13 2 0 20:55 ? 00:00:00 [cpuhp/1] root 14 2 0 20:55 ? 00:00:00 [migration/1] root 15 2 0 20:55 ? 00:00:00 [ksoftirqd/1] root 18 2 0 20:55 ? 00:00:00 [cpuhp/2] root 19 2 0 20:55 ? 00:00:00 [migration/2] root 20 2 0 20:55 ? 00:00:00 [ksoftirqd/2] root 23 2 0 20:55 ? 00:00:00 [cpuhp/3] root 24 2 0 20:55 ? 00:00:00 [migration/3] root 25 2 0 20:55 ? 00:00:00 [ksoftirqd/3] root 28 2 0 20:55 ? 00:00:00 [kdevtmpfs] root 29 2 0 20:55 ? 00:00:00 [netns] root 30 2 0 20:55 ? 00:00:00 [kworker/0:1-mm_percpu_wq] root 32 2 0 20:55 ? 00:00:00 [kauditd] root 33 2 0 20:55 ? 00:00:00 [khungtaskd] root 34 2 0 20:55 ? 00:00:00 [oom_reaper] root 35 2 0 20:55 ? 00:00:00 [writeback] root 36 2 0 20:55 ? 00:00:00 [kcompactd0] root 54 2 0 20:55 ? 00:00:00 [kblockd] root 55 2 0 20:55 ? 00:00:00 [blkcg_punt_bio] root 56 2 0 20:55 ? 00:00:00 [watchdogd] root 57 2 0 20:55 ? 00:00:00 [kworker/2:1-mm_percpu_wq] root 58 2 0 20:55 ? 00:00:00 [rpciod] root 59 2 0 20:55 ? 00:00:00 [kworker/u9:0] root 60 2 0 20:55 ? 00:00:00 [xprtiod] root 61 2 0 20:55 ? 00:00:00 [kswapd0] root 62 2 0 20:55 ? 00:00:00 [nfsiod] root 63 2 0 20:55 ? 00:00:00 [kworker/3:1-events_power_efficient] root 64 2 0 20:55 ? 00:00:00 [iscsi_eh] root 65 2 0 20:55 ? 00:00:00 [dwc_otg] root 66 2 0 20:55 ? 00:00:00 [DWC Notificatio] root 67 2 0 20:55 ? 00:00:00 [kworker/1:2-events_power_efficient] root 68 2 0 20:55 ? 00:00:00 [vchiq-slot/0] root 69 2 0 20:55 ? 00:00:00 [vchiq-recy/0] root 70 2 0 20:55 ? 00:00:00 [vchiq-sync/0] root 71 2 0 20:55 ? 00:00:00 [vchiq-keep/0] root 72 2 0 20:55 ? 00:00:00 [SMIO] root 73 2 0 20:55 ? 00:00:00 [kworker/1:3-mm_percpu_wq] root 79 2 0 20:55 ? 00:00:00 [mmc_complete] root 81 2 0 20:55 ? 00:00:00 [kworker/1:1H-kblockd] root 82 2 0 20:55 ? 00:00:00 [kworker/0:1H-kblockd] root 101 2 0 20:55 ? 00:00:00 [kworker/2:2-events] root 104 2 0 20:55 ? 00:00:00 [kworker/3:1H-kblockd] root 105 2 0 20:55 ? 00:00:00 [kworker/3:2H-kblockd] root 109 2 0 20:55 ? 00:00:00 [kworker/1:2H-kblockd] root 110 2 0 20:55 ? 00:00:00 [jbd2/mmcblk0p2-] root 111 2 0 20:55 ? 00:00:00 [ext4-rsv-conver] root 114 2 0 20:55 ? 00:00:00 [kworker/u8:1-flush-179:0] root 119 2 0 20:55 ? 00:00:01 [loop0] root 122 2 0 20:55 ? 00:00:00 [kworker/2:1H-kblockd] root 146 2 0 20:55 ? 00:00:00 [jbd2/mmcblk0p3-] root 147 2 0 20:55 ? 00:00:00 [ext4-rsv-conver] root 154 2 0 20:55 ? 00:00:00 [kworker/2:2H-kblockd] root 155 2 0 20:55 ? 00:00:00 [ipv6_addrconf] root 184 1 0 20:55 ? 00:00:02 /lib/systemd/systemd-journald root 185 2 0 20:55 ? 00:00:00 [kworker/3:2-events_power_efficient] root 187 2 0 20:55 ? 00:00:00 [kworker/0:2H-kblockd] root 206 1 0 20:55 ? 00:00:01 /lib/systemd/systemd-udevd root 227 2 0 20:55 ? 00:00:00 [SMIO] root 246 2 0 20:55 ? 00:00:00 [mmal-vchiq] root 248 2 0 20:55 ? 00:00:00 [mmal-vchiq] root 250 2 0 20:55 ? 00:00:00 [mmal-vchiq] root 252 2 0 20:55 ? 00:00:00 [mmal-vchiq] root 283 2 0 20:55 ? 00:00:00 [cfg80211] _rpc 352 1 0 20:55 ? 00:00:00 /sbin/rpcbind -f -w root 358 1 0 20:55 ? 00:00:02 /usr/sbin/haveged --Foreground --verbose=1 -w 1024 volumio 386 1 0 20:55 ? 00:00:00 /usr/local/bin/volumio-remote-updater root 388 2 0 20:55 ? 00:00:00 [kworker/3:3-events_power_efficient] nobody 392 1 0 20:55 ? 00:00:00 /usr/sbin/thd --triggers /etc/triggerhappy/triggers.d/ --socket /run/thd.socket --user nobody --deviceglob /dev/input/event* root 394 1 0 20:55 ? 00:00:00 /usr/sbin/alsactl -E HOME=/run/alsa -s -n 19 -c rdaemon avahi 397 1 0 20:55 ? 00:00:00 avahi-daemon: running [volumio3beta-hb-xlr-02.local] message+ 398 1 0 20:55 ? 00:00:01 /usr/bin/dbus-daemon --system --address=systemd: --nofork --nopidfile --systemd-activation --syslog-only root 403 1 0 20:55 ? 00:00:00 /sbin/wpa_supplicant -u -s -O /run/wpa_supplicant root 405 1 0 20:55 ? 00:00:00 /lib/systemd/systemd-logind avahi 412 397 0 20:55 ? 00:00:00 avahi-daemon: chroot helper root 414 2 0 20:55 ? 00:00:00 [RTW_CMD_THREAD] root 471 2 0 20:55 ? 00:00:00 [kworker/u8:3-events_unbound] root 543 1 0 20:55 ? 00:00:01 /usr/sbin/nmbd --foreground --no-process-group root 564 1 0 20:55 ? 00:00:00 /usr/sbin/ifplugd -i eth0 -q -f -u0 -d10 -w -I root 565 1 0 20:55 ? 00:00:00 /sbin/agetty -o -p -- \u --keep-baud 115200,38400,9600 ttyAMA0 vt220 root 566 1 0 20:55 tty1 00:00:00 /sbin/agetty -o -p -- \u --noclear tty1 linux root 575 2 0 20:55 ? 00:00:00 [kworker/0:3-events] ntp 578 1 0 20:55 ? 00:00:00 /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 103:103 root 582 543 0 20:55 ? 00:00:00 /usr/sbin/nmbd --foreground --no-process-group root 620 1 0 20:55 ? 00:00:00 /sbin/dhcpcd eth0 dnsmasq 666 1 0 20:55 ? 00:00:00 /usr/sbin/dnsmasq -x /run/dnsmasq/dnsmasq.pid -u dnsmasq -7 /etc/dnsmasq.d,.dpkg-dist,.dpkg-old,.dpkg-new --local-service root 672 1 0 20:55 ? 00:00:00 /usr/sbin/hostapd -B -P /run/hostapd.pid -B /etc/hostapd/hostapd.conf volumio 674 1 15 20:55 ? 00:00:48 /usr/bin/node /volumio/index.js root 689 1 0 20:55 ? 00:00:00 /usr/sbin/winbindd --foreground --no-process-group root 692 689 0 20:55 ? 00:00:00 winbindd: domain child [VOLUMIO3BETA-HB-XLR-02] root 694 1 0 20:55 ? 00:00:00 /usr/sbin/smbd --foreground --no-process-group root 696 694 0 20:55 ? 00:00:00 /usr/sbin/smbd --foreground --no-process-group root 697 694 0 20:55 ? 00:00:00 /usr/sbin/smbd --foreground --no-process-group root 698 689 0 20:55 ? 00:00:00 winbindd: idmap child root 699 689 0 20:55 ? 00:00:00 winbindd: domain child [BUILTIN] root 700 694 0 20:55 ? 00:00:00 /usr/sbin/smbd --foreground --no-process-group root 740 2 0 20:56 ? 00:00:00 [cifsiod] root 741 2 0 20:56 ? 00:00:00 [smb3decryptd] root 742 2 0 20:56 ? 00:00:00 [cifsfileinfoput] root 743 2 0 20:56 ? 00:00:00 [cifsoplockd] root 746 2 0 20:56 ? 00:00:00 [cifsd] volumio 770 674 0 20:56 ? 00:00:01 /usr/bin/node /volumio/app/plugins/miscellanea/albumart/serverStartup.js 3001 /data/albumart volumio 781 770 1 20:56 ? 00:00:03 /usr/bin/node /volumio/app/plugins/miscellanea/albumart/serverStartup.js 3001 /data/albumart volumio 782 770 1 20:56 ? 00:00:03 /usr/bin/node /volumio/app/plugins/miscellanea/albumart/serverStartup.js 3001 /data/albumart volumio 783 770 1 20:56 ? 00:00:03 /usr/bin/node /volumio/app/plugins/miscellanea/albumart/serverStartup.js 3001 /data/albumart volumio 893 1 0 20:56 ? 00:00:00 /usr/bin/upmpdcli -c /tmp/upmpdcli.conf volumio 921 1 0 20:56 ? 00:00:00 /bin/streaming-daemon mpd 1005 1 4 20:56 ? 00:00:11 /usr/bin/mpd --no-daemon shairpo+ 1021 1 0 20:56 ? 00:00:00 /usr/bin/shairport-sync --configfile=/tmp/shairport-sync.conf volumio 1030 1 0 20:56 ? 00:00:00 /bin/sh /bin/bt/btstart.sh start volumio 1041 1 0 20:56 ? 00:00:00 /bin/sh -c /usr/bin/vtcs volumio 1042 1041 0 20:56 ? 00:00:02 /usr/bin/vtcs volumio 1048 1 0 20:56 ? 00:00:00 /usr/bin/pulseaudio rtkit 1049 1 0 20:56 ? 00:00:00 /usr/lib/rtkit/rtkit-daemon root 1052 1 0 20:56 ? 00:00:00 /usr/lib/policykit-1/polkitd --no-debug root 1072 1 0 20:56 ? 00:00:00 /usr/lib/bluetooth/bluetoothd --noplugin=sap volumio 1077 1030 0 20:56 ? 00:00:01 /usr/bin/python /bin/bt/simple-agent -c NoInputNoOutput volumio 1180 1 0 21:00 ? 00:00:00 /bin/sh /tmp/sshtunnel.sh volumio 1181 1180 0 21:00 ? 00:00:00 /usr/lib/autossh/autossh -M 0 -o ServerAliveInterval 30 -o ServerAliveCountMax 3 -o StrictHostKeyChecking=no -o UserKnownHostsFile=/dev/null -N -p 2222 -R d4nI4wt1NsYcchExrC0uZmuF3FH3-78d06ab9d2a333ed869cad0c1ca2b090.eu2.myvolumio.org:8091:localhost:3000 eu2.myvolumio.org volumio 1184 1181 0 21:00 ? 00:00:00 /usr/bin/ssh -o ServerAliveInterval 30 -o ServerAliveCountMax 3 -o StrictHostKeyChecking=no -o UserKnownHostsFile=/dev/null -N -p 2222 -R d4nI4wt1NsYcchExrC0uZmuF3FH3-78d06ab9d2a333ed869cad0c1ca2b090.eu2.myvolumio.org:8091:localhost:3000 eu2.myvolumio.org root 1187 2 0 21:00 ? 00:00:00 [kworker/1:0-events_freezable] volumio 1188 674 0 21:01 ? 00:00:00 /bin/sh -c /usr/bin/node /volumio/logsubmit.js 'Webradio kein Logo und kein Track-Info ' volumio 1189 1188 74 21:01 ? 00:00:00 /usr/bin/node /volumio/logsubmit.js Webradio kein Logo und kein Track-Info volumio 1209 1189 0 21:01 ? 00:00:00 /bin/sh -c ps -ef >>/tmp/logondemand 2>&1 volumio 1210 1209 0 21:01 ? 00:00:00 ps -ef # df -h --------------- Filesystem Size Used Avail Use% Mounted on /dev/mmcblk0p2 2.5G 1.1G 1.3G 47% /imgpart /dev/loop0 473M 473M 0 100% /static overlay 4.7G 71M 4.4G 2% / devtmpfs 441M 0 441M 0% /dev tmpfs 485M 0 485M 0% /dev/shm tmpfs 485M 8.9M 476M 2% /run tmpfs 5.0M 4.0K 5.0M 1% /run/lock tmpfs 485M 0 485M 0% /sys/fs/cgroup tmpfs 485M 0 485M 0% /var/spool/cups tmpfs 485M 68K 485M 1% /tmp 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.178.11/music 3.5T 2.2T 1.4T 62% /mnt/NAS/Musik # 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=451328k,nr_inodes=112832,mode=755) securityfs on /sys/kernel/security type securityfs (rw,nosuid,nodev,noexec,relatime) tmpfs on /dev/shm type tmpfs (rw,nosuid,nodev,noexec) devpts on /dev/pts type devpts (rw,nosuid,noexec,relatime,gid=5,mode=620,ptmxmode=000) tmpfs on /run type tmpfs (rw,nosuid,nodev,mode=755) tmpfs on /run/lock type tmpfs (rw,nosuid,nodev,noexec,relatime,size=5120k) tmpfs on /sys/fs/cgroup type tmpfs (ro,nosuid,nodev,noexec,mode=755) cgroup2 on /sys/fs/cgroup/unified type cgroup2 (rw,nosuid,nodev,noexec,relatime,nsdelegate) cgroup on /sys/fs/cgroup/systemd type cgroup (rw,nosuid,nodev,noexec,relatime,xattr,name=systemd) none on /sys/fs/bpf type bpf (rw,nosuid,nodev,noexec,relatime,mode=700) cgroup on /sys/fs/cgroup/net_cls,net_prio type cgroup (rw,nosuid,nodev,noexec,relatime,net_cls,net_prio) cgroup on /sys/fs/cgroup/freezer type cgroup (rw,nosuid,nodev,noexec,relatime,freezer) cgroup on /sys/fs/cgroup/perf_event type cgroup (rw,nosuid,nodev,noexec,relatime,perf_event) cgroup on /sys/fs/cgroup/cpu,cpuacct type cgroup (rw,nosuid,nodev,noexec,relatime,cpu,cpuacct) cgroup on /sys/fs/cgroup/pids type cgroup (rw,nosuid,nodev,noexec,relatime,pids) cgroup on /sys/fs/cgroup/cpuset type cgroup (rw,nosuid,nodev,noexec,relatime,cpuset) cgroup on /sys/fs/cgroup/devices type cgroup (rw,nosuid,nodev,noexec,relatime,devices) 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=37,pgrp=1,timeout=0,minproto=5,maxproto=5,direct) configfs on /sys/kernel/config type configfs (rw,relatime) mqueue on /dev/mqueue type mqueue (rw,relatime) sunrpc on /run/rpc_pipefs type rpc_pipefs (rw,relatime) debugfs on /sys/kernel/debug type debugfs (rw,relatime) tmpfs on /var/spool/cups type tmpfs (rw,noatime,mode=755) tmpfs on /tmp type tmpfs (rw,noatime,mode=755) tmpfs on /var/log type tmpfs (rw,nodev,relatime,size=20480k,mode=777,uid=1000,gid=4) tmpfs on /var/spool/cups/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.178.11/music on /mnt/NAS/Musik type cifs (ro,relatime,vers=3.1.1,cache=strict,username=Musik,uid=0,noforceuid,gid=0,noforcegid,addr=192.168.178.11,file_mode=0666,dir_mode=0777,soft,nounix,serverino,mapposix,rsize=4194304,wsize=4194304,bsize=1048576,echo_interval=60,actimeo=1) # netstat -natp --------------- (Not all processes could be identified, non-owned process info will not be shown, you would have to be root to see it all.) Active Internet connections (servers and established) Proto Recv-Q Send-Q Local Address Foreign Address State PID/Program name tcp 0 0 0.0.0.0:5000 0.0.0.0:* LISTEN - tcp 0 0 0.0.0.0:4713 0.0.0.0:* LISTEN 1048/pulseaudio tcp 0 0 0.0.0.0:139 0.0.0.0:* LISTEN - tcp 0 0 0.0.0.0:111 0.0.0.0:* LISTEN - tcp 0 0 0.0.0.0:53 0.0.0.0:* LISTEN - tcp 0 0 0.0.0.0:49149 0.0.0.0:* LISTEN 893/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 893/upmpdcli tcp 0 0 192.168.178.172:59446 84.247.14.230:80 TIME_WAIT - tcp 0 0 192.168.178.172:59698 84.247.14.230:80 ESTABLISHED 674/node tcp 0 0 192.168.178.172:42238 172.67.141.32:443 ESTABLISHED 921/streaming-daemo tcp 0 0 192.168.178.172:41318 192.168.178.11:445 ESTABLISHED - tcp 0 0 192.168.178.172:42240 172.67.141.32:443 ESTABLISHED 921/streaming-daemo tcp 0 0 192.168.178.172:42206 172.67.141.32:443 ESTABLISHED 921/streaming-daemo tcp 0 0 127.0.0.1:40522 127.0.0.1:3000 ESTABLISHED 674/node tcp 0 0 192.168.178.172:56752 195.48.36.151:80 ESTABLISHED - tcp 0 0 192.168.178.172:47478 35.201.97.85:443 ESTABLISHED 674/node tcp 0 0 192.168.178.172:42198 172.67.141.32:443 ESTABLISHED 921/streaming-daemo tcp 0 0 127.0.0.1:40212 127.0.0.1:3000 ESTABLISHED 674/node tcp 0 0 192.168.178.172:59234 188.166.162.121:2222 TIME_WAIT - tcp 0 0 127.0.0.1:49760 127.0.0.1:6599 ESTABLISHED 893/upmpdcli tcp 0 0 192.168.178.172:53068 192.168.178.24:3000 ESTABLISHED 674/node tcp 0 0 127.0.0.1:49764 127.0.0.1:6599 ESTABLISHED 893/upmpdcli tcp 0 0 127.0.0.1:40192 127.0.0.1:3000 ESTABLISHED 386/volumio-remote- tcp 0 0 192.168.178.172:43070 185.14.187.123:2222 ESTABLISHED 1184/ssh tcp6 0 0 :::6599 :::* LISTEN 674/node tcp6 0 0 :::5000 :::* LISTEN - tcp6 0 0 :::6600 :::* LISTEN - tcp6 0 0 :::4713 :::* LISTEN 1048/pulseaudio tcp6 0 0 :::139 :::* LISTEN - tcp6 0 0 :::111 :::* LISTEN - tcp6 0 0 :::53 :::* LISTEN - tcp6 0 0 :::3000 :::* LISTEN 674/node tcp6 0 0 :::3001 :::* LISTEN 770/node tcp6 0 0 :::3005 :::* LISTEN 674/node tcp6 0 0 :::445 :::* LISTEN - tcp6 0 0 :::7777 :::* LISTEN 921/streaming-daemo tcp6 0 0 :::2019 :::* LISTEN 1042/vtcs tcp6 0 0 127.0.0.1:3000 127.0.0.1:40518 TIME_WAIT - tcp6 0 0 127.0.0.1:6599 127.0.0.1:49760 ESTABLISHED 674/node tcp6 0 0 127.0.0.1:3000 127.0.0.1:40528 TIME_WAIT - tcp6 0 0 127.0.0.1:6599 127.0.0.1:49764 ESTABLISHED 674/node tcp6 0 0 127.0.0.1:3000 127.0.0.1:40526 TIME_WAIT - tcp6 0 0 127.0.0.1:3000 127.0.0.1:40212 ESTABLISHED 674/node tcp6 0 0 127.0.0.1:3000 127.0.0.1:40524 TIME_WAIT - tcp6 0 0 192.168.178.172:3000 192.168.178.24:49560 ESTABLISHED 674/node tcp6 0 0 192.168.178.172:3000 192.168.178.182:34544 ESTABLISHED 674/node tcp6 0 0 127.0.0.1:3000 127.0.0.1:40522 ESTABLISHED 674/node tcp6 0 0 127.0.0.1:3000 127.0.0.1:40192 ESTABLISHED 674/node tcp6 0 0 192.168.178.172:3000 192.168.178.153:42668 ESTABLISHED 674/node tcp6 0 0 192.168.178.172:3000 192.168.178.182:34538 ESTABLISHED 674/node tcp6 0 0 127.0.0.1:3000 127.0.0.1:40272 TIME_WAIT - # cat /etc/asound.conf --------------- pcm.!default { type empty slave.pcm "volumio" } pcm.volumio { type empty slave.pcm "volumioMultiRoomServer" } # This PCM switches output to the ALSA target named by the file /tmp/multiroom/server/switch.target pcm.volumioMultiRoomServer { type volumioswitch target_file "/tmp/multiroom/server/switch.target" control_fifo "/tmp/multiroom/server/switch.fifo" } # This PCM switches output to the ALSA target named by the file /tmp/multiroom/client/switch.target pcm.volumioMultiRoomClient { type volumioswitch target_file "/tmp/multiroom/client/switch.target" control_fifo "/tmp/multiroom/client/switch.fifo" } # This PCM performs any necessary rate/format/channel conversion before sending on to the Server FIFO pcm.volumioMultiRoom { type plug slave { pcm "volumioMultiRoomFIFO" rate 48000 format "S16_LE" channels 2 } rate_converter "speexrate_medium" } # Write to the FIFO watched by the Server pcm.volumioMultiRoomFIFO { type volumiofifo fifo "/tmp/multiroom/server/fifo" clear_on_drop false lead_in_frames 16384 } # This pcm is used by pcm.volumioMultiRoomServer and pcm.volumioMultiRoomClient to play audio on the local output pcm.volumioLocalPlayback { type empty slave.pcm "postMultiRoom" } # This pcm is used by pcm.volumioMultiRoomClient to discard audio and allow for faster switching pcm.volumioDiscard { type null } pcm.postMultiRoom { 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 "sndrpihifiberry" } # cat /var/log/mpd.log --------------- Nov 11 20:55 : zeroconf: No global port, disabling zeroconf Nov 11 20:55 : zeroconf: No global port, disabling zeroconf Nov 11 20:55 : client: [0] opened from local Nov 11 20:55 : exception: No such directory Nov 11 20:55 : client: [1] opened from local Nov 11 20:55 : zeroconf: No global port, disabling zeroconf Nov 11 20:55 : client: [0] opened from 127.0.0.1:46470 Nov 11 20:55 : client: [1] opened from 127.0.0.1:46474 Nov 11 20:55 : client: [2] opened from local Nov 11 20:55 : exception: Failed to read mixer for 'alsa': no such mixer control: PCM Nov 11 20:56 : zeroconf: No global port, disabling zeroconf Nov 11 20:56 : client: [0] opened from local Nov 11 20:57 : exception: No such playlist ALSA lib /home/pi/volumio-alsa-plugin/src/pcm_volumioswitch.c:256:(_snd_pcm_volumioswitch_hw_params) PCM volumioMultiRoomServer has buffer size 22050, but target_pcm volumioOutput has buffer size 22048 ALSA lib /home/pi/volumio-alsa-plugin/src/pcm_volumioswitch.c:267:(_snd_pcm_volumioswitch_hw_params) PCM volumioMultiRoomServer has buffer size 22050, but target_pcm volumioOutput has buffer size 22048 Nov 11 20:57 : exception: Failed to open mixer for 'alsa': no such mixer control: PCM Nov 11 20:58 : player: played "http://icecast.argovia.ch/vrock?DIST=TuneIn&TGT=TuneIn&maxServers=2&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTYzNjY2NDIzOSwiaXNzIjoidGlzcnYifQ.4oF3VOwPqosvN-pMQHdH45xJMZmFG7PcBBeo9QlthEc" Nov 11 20:58 : exception: No such playlist ALSA lib /home/pi/volumio-alsa-plugin/src/pcm_volumioswitch.c:256:(_snd_pcm_volumioswitch_hw_params) PCM volumioMultiRoomServer has buffer size 22050, but target_pcm volumioOutput has buffer size 22048 ALSA lib /home/pi/volumio-alsa-plugin/src/pcm_volumioswitch.c:267:(_snd_pcm_volumioswitch_hw_params) PCM volumioMultiRoomServer has buffer size 22050, but target_pcm volumioOutput has buffer size 22048 Nov 11 20:58 : exception: Failed to open mixer for 'alsa': no such mixer control: PCM # 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" } }, "cd_controller": { "status": { "type": "string", "value": "STARTED" } }, "smart_inputs": { "status": { "type": "string", "value": "STARTED" } }, "hi_res_audio": { "status": { "type": "string", "value": "STARTED" } }, "tidal": { "status": { "type": "string", "value": "STARTED" } }, "qobuz": { "status": { "type": "string", "value": "STARTED" } }, "tidalconnect": { "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" } }, "bluetooth": { "status": { "type": "string", "value": "STARTED" } }, "multiroom": { "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" } }, "metavolumio": { "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, 858 ms: OK https://www.googleapis.com, 591 ms: OK https://securetoken.googleapis.com, 702 ms: OK https://myvolumio.firebaseio.com, 928 ms: OK https://functions.volumio.cloud, 894 ms: OK https://oauth-performer.dfs.volumio.org, 629 ms: OK https://browsing-performer.dfs.volumio.org, 687 ms: OK http://cddb.volumio.org, 5247 ms: OK https://functions.volumio.cloud, 1116 ms: OK http://pushupdates.volumio.org, 529 ms: OK http://plugins.volumio.org, 1502 ms: OK https://database.volumio.cloud, 1074 ms: OK https://radio-directory.firebaseapp.com, 668 ms: OK ---------- REMOTE ENDPOINTS TEST OK, all Endpoints are reachable ---------- # sudo journalctl -p 7 --------------- -- Logs begin at Thu 2019-02-14 10:12:00 UTC, end at Thu 2021-11-11 21:01:13 UTC. -- Feb 14 10:12:00 volumio3beta-hb-xlr-02 kernel: Booting Linux on physical CPU 0xf00 Feb 14 10:12:00 volumio3beta-hb-xlr-02 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:12:00 volumio3beta-hb-xlr-02 kernel: CPU: ARMv7 Processor [410fc075] revision 5 (ARMv7), cr=10c5387d Feb 14 10:12:00 volumio3beta-hb-xlr-02 kernel: CPU: div instructions available: patching division code Feb 14 10:12:00 volumio3beta-hb-xlr-02 kernel: CPU: PIPT / VIPT nonaliasing data cache, VIPT aliasing instruction cache Feb 14 10:12:00 volumio3beta-hb-xlr-02 kernel: OF: fdt: Machine model: Raspberry Pi 2 Model B Rev 1.1 Feb 14 10:12:00 volumio3beta-hb-xlr-02 kernel: Memory policy: Data cache writealloc Feb 14 10:12:00 volumio3beta-hb-xlr-02 kernel: Reserved memory: created CMA memory pool at 0x3a000000, size 64 MiB Feb 14 10:12:00 volumio3beta-hb-xlr-02 kernel: OF: reserved mem: initialized node linux,cma, compatible id shared-dma-pool Feb 14 10:12:00 volumio3beta-hb-xlr-02 kernel: On node 0 totalpages: 253952 Feb 14 10:12:00 volumio3beta-hb-xlr-02 kernel: Normal zone: 2232 pages used for memmap Feb 14 10:12:00 volumio3beta-hb-xlr-02 kernel: Normal zone: 0 pages reserved Feb 14 10:12:00 volumio3beta-hb-xlr-02 kernel: Normal zone: 253952 pages, LIFO batch:63 Feb 14 10:12:00 volumio3beta-hb-xlr-02 kernel: percpu: Embedded 20 pages/cpu s49740 r8192 d23988 u81920 Feb 14 10:12:00 volumio3beta-hb-xlr-02 kernel: pcpu-alloc: s49740 r8192 d23988 u81920 alloc=20*4096 Feb 14 10:12:00 volumio3beta-hb-xlr-02 kernel: pcpu-alloc: [0] 0 [0] 1 [0] 2 [0] 3 Feb 14 10:12:00 volumio3beta-hb-xlr-02 kernel: Built 1 zonelists, mobility grouping on. Total pages: 251720 Feb 14 10:12:00 volumio3beta-hb-xlr-02 kernel: Kernel command line: coherent_pool=1M 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:12:00 volumio3beta-hb-xlr-02 kernel: Dentry cache hash table entries: 131072 (order: 7, 524288 bytes, linear) Feb 14 10:12:00 volumio3beta-hb-xlr-02 kernel: Inode-cache hash table entries: 65536 (order: 6, 262144 bytes, linear) Feb 14 10:12:00 volumio3beta-hb-xlr-02 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off Feb 14 10:12:00 volumio3beta-hb-xlr-02 kernel: Memory: 902656K/1015808K available (9216K kernel code, 698K rwdata, 2608K rodata, 1024K init, 827K bss, 47616K reserved, 65536K cma-reserved) Feb 14 10:12:00 volumio3beta-hb-xlr-02 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 Feb 14 10:12:00 volumio3beta-hb-xlr-02 kernel: ftrace: allocating 29205 entries in 58 pages Feb 14 10:12:00 volumio3beta-hb-xlr-02 kernel: rcu: Hierarchical RCU implementation. Feb 14 10:12:00 volumio3beta-hb-xlr-02 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 10 jiffies. Feb 14 10:12:00 volumio3beta-hb-xlr-02 kernel: NR_IRQS: 16, nr_irqs: 16, preallocated irqs: 16 Feb 14 10:12:00 volumio3beta-hb-xlr-02 kernel: random: get_random_bytes called from start_kernel+0x324/0x4f8 with crng_init=0 Feb 14 10:12:00 volumio3beta-hb-xlr-02 kernel: arch_timer: cp15 timer(s) running at 19.20MHz (phys). Feb 14 10:12:00 volumio3beta-hb-xlr-02 kernel: clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x46d987e47, max_idle_ns: 440795202767 ns Feb 14 10:12:00 volumio3beta-hb-xlr-02 kernel: sched_clock: 56 bits at 19MHz, resolution 52ns, wraps every 4398046511078ns Feb 14 10:12:00 volumio3beta-hb-xlr-02 kernel: Switching to timer-based delay loop, resolution 52ns Feb 14 10:12:00 volumio3beta-hb-xlr-02 kernel: Console: colour dummy device 80x30 Feb 14 10:12:00 volumio3beta-hb-xlr-02 kernel: printk: console [tty1] enabled Feb 14 10:12:00 volumio3beta-hb-xlr-02 kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 38.40 BogoMIPS (lpj=192000) Feb 14 10:12:00 volumio3beta-hb-xlr-02 kernel: pid_max: default: 32768 minimum: 301 Feb 14 10:12:00 volumio3beta-hb-xlr-02 kernel: LSM: Security Framework initializing Feb 14 10:12:00 volumio3beta-hb-xlr-02 kernel: Mount-cache hash table entries: 2048 (order: 1, 8192 bytes, linear) Feb 14 10:12:00 volumio3beta-hb-xlr-02 kernel: Mountpoint-cache hash table entries: 2048 (order: 1, 8192 bytes, linear) Feb 14 10:12:00 volumio3beta-hb-xlr-02 kernel: Disabling memory control group subsystem Feb 14 10:12:00 volumio3beta-hb-xlr-02 kernel: CPU: Testing write buffer coherency: ok Feb 14 10:12:00 volumio3beta-hb-xlr-02 kernel: CPU0: update cpu_capacity 1024 Feb 14 10:12:00 volumio3beta-hb-xlr-02 kernel: CPU0: thread -1, cpu 0, socket 15, mpidr 80000f00 Feb 14 10:12:00 volumio3beta-hb-xlr-02 kernel: Setting up static identity map for 0x100000 - 0x10003c Feb 14 10:12:00 volumio3beta-hb-xlr-02 kernel: rcu: Hierarchical SRCU implementation. Feb 14 10:12:00 volumio3beta-hb-xlr-02 kernel: smp: Bringing up secondary CPUs ... Feb 14 10:12:00 volumio3beta-hb-xlr-02 kernel: CPU1: update cpu_capacity 1024 Feb 14 10:12:00 volumio3beta-hb-xlr-02 kernel: CPU1: thread -1, cpu 1, socket 15, mpidr 80000f01 Feb 14 10:12:00 volumio3beta-hb-xlr-02 kernel: CPU2: update cpu_capacity 1024 Feb 14 10:12:00 volumio3beta-hb-xlr-02 kernel: CPU2: thread -1, cpu 2, socket 15, mpidr 80000f02 Feb 14 10:12:00 volumio3beta-hb-xlr-02 kernel: CPU3: update cpu_capacity 1024 Feb 14 10:12:00 volumio3beta-hb-xlr-02 kernel: CPU3: thread -1, cpu 3, socket 15, mpidr 80000f03 Feb 14 10:12:00 volumio3beta-hb-xlr-02 kernel: smp: Brought up 1 node, 4 CPUs Feb 14 10:12:00 volumio3beta-hb-xlr-02 kernel: SMP: Total of 4 processors activated (153.60 BogoMIPS). Feb 14 10:12:00 volumio3beta-hb-xlr-02 kernel: CPU: All CPU(s) started in HYP mode. Feb 14 10:12:00 volumio3beta-hb-xlr-02 kernel: CPU: Virtualization extensions available. Feb 14 10:12:00 volumio3beta-hb-xlr-02 kernel: devtmpfs: initialized Feb 14 10:12:00 volumio3beta-hb-xlr-02 kernel: VFP support v0.3: implementor 41 architecture 2 part 30 variant 7 rev 5 Feb 14 10:12:00 volumio3beta-hb-xlr-02 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604462750000 ns Feb 14 10:12:00 volumio3beta-hb-xlr-02 kernel: futex hash table entries: 1024 (order: 4, 65536 bytes, linear) Feb 14 10:12:00 volumio3beta-hb-xlr-02 kernel: pinctrl core: initialized pinctrl subsystem Feb 14 10:12:00 volumio3beta-hb-xlr-02 kernel: NET: Registered protocol family 16 Feb 14 10:12:00 volumio3beta-hb-xlr-02 kernel: DMA: preallocated 1024 KiB pool for atomic coherent allocations Feb 14 10:12:00 volumio3beta-hb-xlr-02 kernel: audit: initializing netlink subsys (disabled) Feb 14 10:12:00 volumio3beta-hb-xlr-02 kernel: audit: type=2000 audit(0.050:1): state=initialized audit_enabled=0 res=1 Feb 14 10:12:00 volumio3beta-hb-xlr-02 kernel: hw-breakpoint: found 5 (+1 reserved) breakpoint and 4 watchpoint registers. Feb 14 10:12:00 volumio3beta-hb-xlr-02 kernel: hw-breakpoint: maximum watchpoint size is 8 bytes. Feb 14 10:12:00 volumio3beta-hb-xlr-02 kernel: Serial: AMBA PL011 UART driver Feb 14 10:12:00 volumio3beta-hb-xlr-02 kernel: bcm2835-mbox 3f00b880.mailbox: mailbox enabled Feb 14 10:12:00 volumio3beta-hb-xlr-02 kernel: raspberrypi-firmware soc:firmware: Attached to firmware from 2021-01-08 14:33, variant start Feb 14 10:12:00 volumio3beta-hb-xlr-02 kernel: raspberrypi-firmware soc:firmware: Firmware hash is 194a85abd768c7334bbadc3f1911c10a7d18ed14 Feb 14 10:12:00 volumio3beta-hb-xlr-02 kernel: bcm2835-dma 3f007000.dma: DMA legacy API manager, dmachans=0x1 Feb 14 10:12:00 volumio3beta-hb-xlr-02 kernel: SCSI subsystem initialized Feb 14 10:12:00 volumio3beta-hb-xlr-02 kernel: usbcore: registered new interface driver usbfs Feb 14 10:12:00 volumio3beta-hb-xlr-02 kernel: usbcore: registered new interface driver hub Feb 14 10:12:00 volumio3beta-hb-xlr-02 kernel: usbcore: registered new device driver usb Feb 14 10:12:00 volumio3beta-hb-xlr-02 kernel: clocksource: Switched to clocksource arch_sys_counter Feb 14 10:12:00 volumio3beta-hb-xlr-02 kernel: VFS: Disk quotas dquot_6.6.0 Feb 14 10:12:00 volumio3beta-hb-xlr-02 kernel: VFS: Dquot-cache hash table entries: 1024 (order 0, 4096 bytes) Feb 14 10:12:00 volumio3beta-hb-xlr-02 kernel: FS-Cache: Loaded Feb 14 10:12:00 volumio3beta-hb-xlr-02 kernel: CacheFiles: Loaded Feb 14 10:12:00 volumio3beta-hb-xlr-02 kernel: thermal_sys: Registered thermal governor 'step_wise' Feb 14 10:12:00 volumio3beta-hb-xlr-02 kernel: NET: Registered protocol family 2 Feb 14 10:12:00 volumio3beta-hb-xlr-02 kernel: tcp_listen_portaddr_hash hash table entries: 512 (order: 0, 6144 bytes, linear) Feb 14 10:12:00 volumio3beta-hb-xlr-02 kernel: TCP established hash table entries: 8192 (order: 3, 32768 bytes, linear) Feb 14 10:12:00 volumio3beta-hb-xlr-02 kernel: TCP bind hash table entries: 8192 (order: 4, 65536 bytes, linear) Feb 14 10:12:00 volumio3beta-hb-xlr-02 kernel: TCP: Hash tables configured (established 8192 bind 8192) Feb 14 10:12:00 volumio3beta-hb-xlr-02 kernel: UDP hash table entries: 512 (order: 2, 16384 bytes, linear) Feb 14 10:12:00 volumio3beta-hb-xlr-02 kernel: UDP-Lite hash table entries: 512 (order: 2, 16384 bytes, linear) Feb 14 10:12:00 volumio3beta-hb-xlr-02 kernel: NET: Registered protocol family 1 Feb 14 10:12:00 volumio3beta-hb-xlr-02 kernel: RPC: Registered named UNIX socket transport module. Feb 14 10:12:00 volumio3beta-hb-xlr-02 kernel: RPC: Registered udp transport module. Feb 14 10:12:00 volumio3beta-hb-xlr-02 kernel: RPC: Registered tcp transport module. Feb 14 10:12:00 volumio3beta-hb-xlr-02 kernel: RPC: Registered tcp NFSv4.1 backchannel transport module. Feb 14 10:12:00 volumio3beta-hb-xlr-02 kernel: Trying to unpack rootfs image as initramfs... Feb 14 10:12:00 volumio3beta-hb-xlr-02 kernel: Freeing initrd memory: 22488K Feb 14 10:12:00 volumio3beta-hb-xlr-02 kernel: hw perfevents: enabled with armv7_cortex_a7 PMU driver, 5 counters available Feb 14 10:12:00 volumio3beta-hb-xlr-02 kernel: Initialise system trusted keyrings Feb 14 10:12:00 volumio3beta-hb-xlr-02 kernel: workingset: timestamp_bits=14 max_order=18 bucket_order=4 Feb 14 10:12:00 volumio3beta-hb-xlr-02 kernel: FS-Cache: Netfs 'nfs' registered for caching Feb 14 10:12:00 volumio3beta-hb-xlr-02 kernel: NFS: Registering the id_resolver key type Feb 14 10:12:00 volumio3beta-hb-xlr-02 kernel: Key type id_resolver registered Feb 14 10:12:00 volumio3beta-hb-xlr-02 kernel: Key type id_legacy registered Feb 14 10:12:00 volumio3beta-hb-xlr-02 kernel: nfs4filelayout_init: NFSv4 File Layout Driver Registering... Feb 14 10:12:00 volumio3beta-hb-xlr-02 kernel: Key type asymmetric registered Feb 14 10:12:00 volumio3beta-hb-xlr-02 kernel: Asymmetric key parser 'x509' registered Feb 14 10:12:00 volumio3beta-hb-xlr-02 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 249) Feb 14 10:12:00 volumio3beta-hb-xlr-02 kernel: io scheduler mq-deadline registered Feb 14 10:12:00 volumio3beta-hb-xlr-02 kernel: io scheduler kyber registered Feb 14 10:12:00 volumio3beta-hb-xlr-02 kernel: bcm2708_fb soc:fb: FB found 1 display(s) Feb 14 10:12:00 volumio3beta-hb-xlr-02 kernel: Console: switching to colour frame buffer device 80x30 Feb 14 10:12:00 volumio3beta-hb-xlr-02 kernel: bcm2708_fb soc:fb: Registered framebuffer for display 0, size 640x480 Feb 14 10:12:00 volumio3beta-hb-xlr-02 kernel: bcm2835-rng 3f104000.rng: hwrng registered Feb 14 10:12:00 volumio3beta-hb-xlr-02 kernel: vc-mem: phys_addr:0x00000000 mem_base=0x3ec00000 mem_size:0x40000000(1024 MiB) Feb 14 10:12:00 volumio3beta-hb-xlr-02 kernel: vc-sm: Videocore shared memory driver Feb 14 10:12:00 volumio3beta-hb-xlr-02 kernel: gpiomem-bcm2835 3f200000.gpiomem: Initialised: Registers at 0x3f200000 Feb 14 10:12:00 volumio3beta-hb-xlr-02 kernel: brd: module loaded Feb 14 10:12:00 volumio3beta-hb-xlr-02 kernel: loop: module loaded Feb 14 10:12:00 volumio3beta-hb-xlr-02 kernel: Loading iSCSI transport class v2.0-870. Feb 14 10:12:00 volumio3beta-hb-xlr-02 kernel: libphy: Fixed MDIO Bus: probed Feb 14 10:12:00 volumio3beta-hb-xlr-02 kernel: usbcore: registered new interface driver lan78xx Feb 14 10:12:00 volumio3beta-hb-xlr-02 kernel: usbcore: registered new interface driver smsc95xx Feb 14 10:12:00 volumio3beta-hb-xlr-02 kernel: dwc_otg: version 3.00a 10-AUG-2012 (platform bus) Feb 14 10:12:00 volumio3beta-hb-xlr-02 kernel: Core Release: 2.80a Feb 14 10:12:00 volumio3beta-hb-xlr-02 kernel: Setting default values for core params Feb 14 10:12:00 volumio3beta-hb-xlr-02 kernel: Finished setting default values for core params Feb 14 10:12:00 volumio3beta-hb-xlr-02 kernel: Using Buffer DMA mode Feb 14 10:12:00 volumio3beta-hb-xlr-02 kernel: Periodic Transfer Interrupt Enhancement - disabled Feb 14 10:12:00 volumio3beta-hb-xlr-02 kernel: Multiprocessor Interrupt Enhancement - disabled Feb 14 10:12:00 volumio3beta-hb-xlr-02 kernel: OTG VER PARAM: 0, OTG VER FLAG: 0 Feb 14 10:12:00 volumio3beta-hb-xlr-02 kernel: Dedicated Tx FIFOs mode Feb 14 10:12:00 volumio3beta-hb-xlr-02 kernel: WARN::dwc_otg_hcd_init:1074: FIQ DMA bounce buffers: virt = ba114000 dma = 0xfa114000 len=9024 Nov 11 20:55:15 volumio3beta-hb-xlr-02 kernel: FIQ FSM acceleration enabled for : Non-periodic Split Transactions Periodic Split Transactions High-Speed Isochronous Endpoints Interrupt/Control Split Transaction hack enabled Nov 11 20:55:15 volumio3beta-hb-xlr-02 kernel: dwc_otg: Microframe scheduler enabled Nov 11 20:55:15 volumio3beta-hb-xlr-02 kernel: WARN::hcd_init_fiq:457: FIQ on core 1 Nov 11 20:55:15 volumio3beta-hb-xlr-02 kernel: WARN::hcd_init_fiq:458: FIQ ASM at 8070bf24 length 36 Nov 11 20:55:15 volumio3beta-hb-xlr-02 kernel: WARN::hcd_init_fiq:497: MPHI regs_base at be810000 Nov 11 20:55:15 volumio3beta-hb-xlr-02 kernel: dwc_otg 3f980000.usb: DWC OTG Controller Nov 11 20:55:15 volumio3beta-hb-xlr-02 kernel: dwc_otg 3f980000.usb: new USB bus registered, assigned bus number 1 Nov 11 20:55:15 volumio3beta-hb-xlr-02 kernel: dwc_otg 3f980000.usb: irq 56, io mem 0x00000000 Nov 11 20:55:15 volumio3beta-hb-xlr-02 kernel: Init: Port Power? op_state=1 Nov 11 20:55:15 volumio3beta-hb-xlr-02 kernel: Init: Power Port (0) Nov 11 20:55:15 volumio3beta-hb-xlr-02 kernel: usb usb1: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 5.04 Nov 11 20:55:15 volumio3beta-hb-xlr-02 kernel: usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1 Nov 11 20:55:15 volumio3beta-hb-xlr-02 kernel: usb usb1: Product: DWC OTG Controller Nov 11 20:55:15 volumio3beta-hb-xlr-02 kernel: usb usb1: Manufacturer: Linux 5.4.83-v7+ dwc_otg_hcd Nov 11 20:55:15 volumio3beta-hb-xlr-02 kernel: usb usb1: SerialNumber: 3f980000.usb Nov 11 20:55:15 volumio3beta-hb-xlr-02 kernel: hub 1-0:1.0: USB hub found Nov 11 20:55:15 volumio3beta-hb-xlr-02 kernel: hub 1-0:1.0: 1 port detected Nov 11 20:55:15 volumio3beta-hb-xlr-02 kernel: dwc_otg: FIQ enabled Nov 11 20:55:15 volumio3beta-hb-xlr-02 kernel: dwc_otg: NAK holdoff enabled Nov 11 20:55:15 volumio3beta-hb-xlr-02 kernel: dwc_otg: FIQ split-transaction FSM enabled Nov 11 20:55:15 volumio3beta-hb-xlr-02 kernel: Module dwc_common_port init Nov 11 20:55:15 volumio3beta-hb-xlr-02 kernel: usbcore: registered new interface driver usb-storage Nov 11 20:55:15 volumio3beta-hb-xlr-02 kernel: mousedev: PS/2 mouse device common for all mice Nov 11 20:55:15 volumio3beta-hb-xlr-02 kernel: bcm2835-wdt bcm2835-wdt: Broadcom BCM2835 watchdog timer Nov 11 20:55:15 volumio3beta-hb-xlr-02 kernel: sdhci: Secure Digital Host Controller Interface driver Nov 11 20:55:15 volumio3beta-hb-xlr-02 kernel: sdhci: Copyright(c) Pierre Ossman Nov 11 20:55:15 volumio3beta-hb-xlr-02 kernel: sdhost-bcm2835 3f202000.mmc: could not get clk, deferring probe Nov 11 20:55:15 volumio3beta-hb-xlr-02 kernel: sdhci-pltfm: SDHCI platform and OF driver helper Nov 11 20:55:15 volumio3beta-hb-xlr-02 kernel: ledtrig-cpu: registered to indicate activity on CPUs Nov 11 20:55:15 volumio3beta-hb-xlr-02 kernel: hidraw: raw HID events driver (C) Jiri Kosina Nov 11 20:55:15 volumio3beta-hb-xlr-02 kernel: usbcore: registered new interface driver usbhid Nov 11 20:55:15 volumio3beta-hb-xlr-02 kernel: usbhid: USB HID core driver Nov 11 20:55:15 volumio3beta-hb-xlr-02 kernel: vchiq: vchiq_init_state: slot_zero = (ptrval) Nov 11 20:55:15 volumio3beta-hb-xlr-02 kernel: [vc_sm_connected_init]: start Nov 11 20:55:15 volumio3beta-hb-xlr-02 kernel: [vc_sm_connected_init]: end - returning 0 Nov 11 20:55:15 volumio3beta-hb-xlr-02 kernel: Initializing XFRM netlink socket Nov 11 20:55:15 volumio3beta-hb-xlr-02 kernel: NET: Registered protocol family 17 Nov 11 20:55:15 volumio3beta-hb-xlr-02 kernel: Key type dns_resolver registered Nov 11 20:55:15 volumio3beta-hb-xlr-02 kernel: Registering SWP/SWPB emulation handler Nov 11 20:55:15 volumio3beta-hb-xlr-02 kernel: registered taskstats version 1 Nov 11 20:55:15 volumio3beta-hb-xlr-02 kernel: Loading compiled-in X.509 certificates Nov 11 20:55:15 volumio3beta-hb-xlr-02 kernel: Key type ._fscrypt registered Nov 11 20:55:15 volumio3beta-hb-xlr-02 kernel: Key type .fscrypt registered Nov 11 20:55:15 volumio3beta-hb-xlr-02 kernel: uart-pl011 3f201000.serial: cts_event_workaround enabled Nov 11 20:55:15 volumio3beta-hb-xlr-02 kernel: 3f201000.serial: ttyAMA0 at MMIO 0x3f201000 (irq = 81, base_baud = 0) is a PL011 rev2 Nov 11 20:55:15 volumio3beta-hb-xlr-02 kernel: printk: console [ttyAMA0] enabled Nov 11 20:55:15 volumio3beta-hb-xlr-02 kernel: bcm2835-power bcm2835-power: Broadcom BCM2835 power domains driver Nov 11 20:55:15 volumio3beta-hb-xlr-02 kernel: sdhost: log_buf @ (ptrval) (fa113000) Nov 11 20:55:15 volumio3beta-hb-xlr-02 kernel: mmc0: sdhost-bcm2835 loaded - DMA enabled (>1) Nov 11 20:55:15 volumio3beta-hb-xlr-02 kernel: of_cfs_init Nov 11 20:55:15 volumio3beta-hb-xlr-02 kernel: of_cfs_init: OK Nov 11 20:55:15 volumio3beta-hb-xlr-02 kernel: Freeing unused kernel memory: 1024K Nov 11 20:55:15 volumio3beta-hb-xlr-02 kernel: Run /init as init process Nov 11 20:55:15 volumio3beta-hb-xlr-02 kernel: mmc0: host does not support reading read-only switch, assuming write-enable Nov 11 20:55:15 volumio3beta-hb-xlr-02 kernel: mmc0: new high speed SDHC card at address 0001 Nov 11 20:55:15 volumio3beta-hb-xlr-02 kernel: mmcblk0: mmc0:0001 ASTC 7.44 GiB Nov 11 20:55:15 volumio3beta-hb-xlr-02 kernel: mmcblk0: p1 p2 p3 Nov 11 20:55:15 volumio3beta-hb-xlr-02 kernel: Indeed it is in host mode hprt0 = 00021501 Nov 11 20:55:15 volumio3beta-hb-xlr-02 kernel: random: fast init done Nov 11 20:55:15 volumio3beta-hb-xlr-02 initramfs: Booting Volumio for BCM2835 Nov 11 20:55:15 volumio3beta-hb-xlr-02 initramfs: This script mounts rootfs RO with an overlay RW layer. Nov 11 20:55:15 volumio3beta-hb-xlr-02 kernel: usb 1-1: new high-speed USB device number 2 using dwc_otg Nov 11 20:55:15 volumio3beta-hb-xlr-02 kernel: Indeed it is in host mode hprt0 = 00001101 Nov 11 20:55:15 volumio3beta-hb-xlr-02 kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher Nov 11 20:55:15 volumio3beta-hb-xlr-02 initramfs: IMGPART=/dev/mmcblk0p2 Nov 11 20:55:15 volumio3beta-hb-xlr-02 initramfs: IMGFILE=/volumio_current.sqsh Nov 11 20:55:15 volumio3beta-hb-xlr-02 initramfs: Boot delay (except first time) will be 5 seconds Nov 11 20:55:15 volumio3beta-hb-xlr-02 initramfs: /dev/mmcblk0p2: Nov 11 20:55:15 volumio3beta-hb-xlr-02 kernel: EXT4-fs (mmcblk0p2): recovery complete Nov 11 20:55:15 volumio3beta-hb-xlr-02 kernel: EXT4-fs (mmcblk0p2): mounted filesystem with ordered data mode. Opts: (null) Nov 11 20:55:15 volumio3beta-hb-xlr-02 initramfs: Doing a 5 second delay here to give kernel load a headstart Nov 11 20:55:15 volumio3beta-hb-xlr-02 kernel: usb 1-1: New USB device found, idVendor=0424, idProduct=9514, bcdDevice= 2.00 Nov 11 20:55:15 volumio3beta-hb-xlr-02 kernel: usb 1-1: New USB device strings: Mfr=0, Product=0, SerialNumber=0 Nov 11 20:55:15 volumio3beta-hb-xlr-02 kernel: hub 1-1:1.0: USB hub found Nov 11 20:55:15 volumio3beta-hb-xlr-02 kernel: hub 1-1:1.0: 5 ports detected Nov 11 20:55:15 volumio3beta-hb-xlr-02 kernel: usb 1-1.1: new high-speed USB device number 3 using dwc_otg Nov 11 20:55:15 volumio3beta-hb-xlr-02 kernel: usb 1-1.1: New USB device found, idVendor=0424, idProduct=ec00, bcdDevice= 2.00 Nov 11 20:55:15 volumio3beta-hb-xlr-02 kernel: usb 1-1.1: New USB device strings: Mfr=0, Product=0, SerialNumber=0 Nov 11 20:55:15 volumio3beta-hb-xlr-02 kernel: smsc95xx v1.0.6 Nov 11 20:55:15 volumio3beta-hb-xlr-02 kernel: smsc95xx 1-1.1:1.0 eth0: register 'smsc95xx' at usb-3f980000.usb-1.1, smsc95xx USB 2.0 Ethernet, b8:27:eb:43:bd:38 Nov 11 20:55:15 volumio3beta-hb-xlr-02 kernel: usb 1-1.2: new high-speed USB device number 4 using dwc_otg Nov 11 20:55:15 volumio3beta-hb-xlr-02 kernel: usb 1-1.2: New USB device found, idVendor=0bda, idProduct=a811, bcdDevice= 2.00 Nov 11 20:55:15 volumio3beta-hb-xlr-02 kernel: usb 1-1.2: New USB device strings: Mfr=1, Product=2, SerialNumber=3 Nov 11 20:55:15 volumio3beta-hb-xlr-02 kernel: usb 1-1.2: Product: 802.11ac WLAN Adapter Nov 11 20:55:15 volumio3beta-hb-xlr-02 kernel: usb 1-1.2: Manufacturer: Realtek Nov 11 20:55:15 volumio3beta-hb-xlr-02 kernel: usb 1-1.2: SerialNumber: 00e04c000001 Nov 11 20:55:15 volumio3beta-hb-xlr-02 initramfs: Checking for USB updates Nov 11 20:55:15 volumio3beta-hb-xlr-02 initramfs: No USB device detected (when incorrect, try adding 'bootdelay=5' to your boot cmdline) Nov 11 20:55:15 volumio3beta-hb-xlr-02 kernel: EXT4-fs (mmcblk0p3): recovery complete Nov 11 20:55:15 volumio3beta-hb-xlr-02 kernel: EXT4-fs (mmcblk0p3): mounted filesystem with ordered data mode. Opts: (null) Nov 11 20:55:15 volumio3beta-hb-xlr-02 initramfs: With Option: Nov 11 20:55:15 volumio3beta-hb-xlr-02 initramfs: VOLUMIO_VERSION="3.139" Nov 11 20:55:15 volumio3beta-hb-xlr-02 initramfs: Finish initramfs, continue booting Volumio Nov 11 20:55:15 volumio3beta-hb-xlr-02 systemd[1]: System time before build time, advancing clock. Nov 11 20:55:15 volumio3beta-hb-xlr-02 kernel: NET: Registered protocol family 10 Nov 11 20:55:15 volumio3beta-hb-xlr-02 kernel: Segment Routing with IPv6 Nov 11 20:55:15 volumio3beta-hb-xlr-02 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) Nov 11 20:55:15 volumio3beta-hb-xlr-02 systemd[1]: Detected architecture arm. Nov 11 20:55:15 volumio3beta-hb-xlr-02 systemd[1]: Set hostname to . Nov 11 20:55:15 volumio3beta-hb-xlr-02 kernel: uart-pl011 3f201000.serial: no DMA platform data Nov 11 20:55:15 volumio3beta-hb-xlr-02 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. Nov 11 20:55:15 volumio3beta-hb-xlr-02 kernel: random: systemd: uninitialized urandom read (16 bytes read) Nov 11 20:55:15 volumio3beta-hb-xlr-02 kernel: random: systemd: uninitialized urandom read (16 bytes read) Nov 11 20:55:15 volumio3beta-hb-xlr-02 kernel: random: systemd: uninitialized urandom read (16 bytes read) Nov 11 20:55:15 volumio3beta-hb-xlr-02 systemd[1]: Listening on udev Control Socket. Nov 11 20:55:15 volumio3beta-hb-xlr-02 systemd[1]: Created slice system-systemd\x2dfsck.slice. Nov 11 20:55:15 volumio3beta-hb-xlr-02 systemd[1]: Created slice User and Session Slice. Nov 11 20:55:15 volumio3beta-hb-xlr-02 systemd[1]: Created slice system-getty.slice. Nov 11 20:55:15 volumio3beta-hb-xlr-02 systemd[1]: Reached target Slices. Nov 11 20:55:15 volumio3beta-hb-xlr-02 systemd[1]: Listening on udev Kernel Socket. Nov 11 20:55:15 volumio3beta-hb-xlr-02 kernel: i2c /dev entries driver Nov 11 20:55:15 volumio3beta-hb-xlr-02 systemd-journald[184]: Journal started Nov 11 20:55:15 volumio3beta-hb-xlr-02 systemd-journald[184]: Runtime journal (/run/log/journal/cb720c53a9227cf874cddc8961606719) is 7.5M, max 30.0M, 22.5M free. Nov 11 20:55:15 volumio3beta-hb-xlr-02 systemd-modules-load[174]: Inserted module 'i2c_dev' Nov 11 20:55:15 volumio3beta-hb-xlr-02 systemd[1]: Mounted RPC Pipe File System. Nov 11 20:55:15 volumio3beta-hb-xlr-02 fake-hwclock[181]: Thu Nov 11 20:55:15 UTC 2021 Nov 11 20:55:15 volumio3beta-hb-xlr-02 systemd[1]: Started Load/Save Random Seed. Nov 11 20:55:15 volumio3beta-hb-xlr-02 systemd[1]: Started Create System Users. Nov 11 20:55:15 volumio3beta-hb-xlr-02 systemd[1]: Starting Create Static Device Nodes in /dev... Nov 11 20:55:15 volumio3beta-hb-xlr-02 systemd[1]: dynamicswap.service: Succeeded. Nov 11 20:55:15 volumio3beta-hb-xlr-02 systemd[1]: Started Create Static Device Nodes in /dev. Nov 11 20:55:15 volumio3beta-hb-xlr-02 systemd[1]: Reached target Local File Systems (Pre). Nov 11 20:55:15 volumio3beta-hb-xlr-02 systemd[1]: Mounting /var/spool/cups... Nov 11 20:55:15 volumio3beta-hb-xlr-02 systemd[1]: tmp.mount: Directory /tmp to mount over is not empty, mounting anyway. Nov 11 20:55:15 volumio3beta-hb-xlr-02 systemd[1]: Mounting /tmp... Nov 11 20:55:15 volumio3beta-hb-xlr-02 systemd[1]: var-log.mount: Directory /var/log to mount over is not empty, mounting anyway. Nov 11 20:55:15 volumio3beta-hb-xlr-02 systemd[1]: Mounting /var/log... Nov 11 20:55:15 volumio3beta-hb-xlr-02 systemd[1]: Starting udev Kernel Device Manager... Nov 11 20:55:15 volumio3beta-hb-xlr-02 systemd[1]: Mounted /var/spool/cups. Nov 11 20:55:15 volumio3beta-hb-xlr-02 systemd[1]: Mounted /tmp. Nov 11 20:55:15 volumio3beta-hb-xlr-02 systemd[1]: Mounted /var/log. Nov 11 20:55:15 volumio3beta-hb-xlr-02 systemd[1]: Starting Flush Journal to Persistent Storage... Nov 11 20:55:15 volumio3beta-hb-xlr-02 systemd[1]: Mounting /var/spool/cups/tmp... Nov 11 20:55:15 volumio3beta-hb-xlr-02 systemd[1]: Mounted /var/spool/cups/tmp. Nov 11 20:55:15 volumio3beta-hb-xlr-02 systemd-udevd[206]: Network interface NamePolicy= disabled on kernel command line, ignoring. Nov 11 20:55:15 volumio3beta-hb-xlr-02 systemd[1]: Started udev Coldplug all Devices. Nov 11 20:55:15 volumio3beta-hb-xlr-02 systemd[1]: Starting Helper to synchronize boot up for ifupdown... Nov 11 20:55:15 volumio3beta-hb-xlr-02 systemd-journald[184]: Runtime journal (/run/log/journal/cb720c53a9227cf874cddc8961606719) is 7.5M, max 30.0M, 22.5M free. Nov 11 20:55:15 volumio3beta-hb-xlr-02 systemd[1]: Started Flush Journal to Persistent Storage. Nov 11 20:55:15 volumio3beta-hb-xlr-02 systemd[1]: Started udev Kernel Device Manager. Nov 11 20:55:15 volumio3beta-hb-xlr-02 systemd[1]: Starting Show Plymouth Boot Screen... Nov 11 20:55:15 volumio3beta-hb-xlr-02 systemd[1]: Started Helper to synchronize boot up for ifupdown. Nov 11 20:55:15 volumio3beta-hb-xlr-02 systemd[1]: Received SIGRTMIN+20 from PID 223 (plymouthd). Nov 11 20:55:15 volumio3beta-hb-xlr-02 systemd[1]: Started Show Plymouth Boot Screen. Nov 11 20:55:15 volumio3beta-hb-xlr-02 systemd[1]: Started Forward Password Requests to Plymouth Directory Watch. Nov 11 20:55:15 volumio3beta-hb-xlr-02 systemd[1]: Condition check resulted in Dispatch Password Requests to Console Directory Watch being skipped. Nov 11 20:55:15 volumio3beta-hb-xlr-02 systemd[1]: Reached target Paths. Nov 11 20:55:15 volumio3beta-hb-xlr-02 systemd[1]: Reached target Local Encrypted Volumes. Nov 11 20:55:16 volumio3beta-hb-xlr-02 kernel: vc_sm_cma: module is from the staging directory, the quality is unknown, you have been warned. Nov 11 20:55:16 volumio3beta-hb-xlr-02 kernel: bcm2835_vc_sm_cma_probe: Videocore shared memory driver Nov 11 20:55:16 volumio3beta-hb-xlr-02 kernel: [vc_sm_connected_init]: start Nov 11 20:55:16 volumio3beta-hb-xlr-02 kernel: [vc_sm_connected_init]: installed successfully Nov 11 20:55:16 volumio3beta-hb-xlr-02 kernel: mc: Linux media interface: v0.10 Nov 11 20:55:16 volumio3beta-hb-xlr-02 kernel: videodev: Linux video capture interface: v2.00 Nov 11 20:55:16 volumio3beta-hb-xlr-02 kernel: bcm2835_mmal_vchiq: module is from the staging directory, the quality is unknown, you have been warned. Nov 11 20:55:16 volumio3beta-hb-xlr-02 kernel: bcm2835_mmal_vchiq: module is from the staging directory, the quality is unknown, you have been warned. Nov 11 20:55:16 volumio3beta-hb-xlr-02 kernel: bcm2835_mmal_vchiq: module is from the staging directory, the quality is unknown, you have been warned. Nov 11 20:55:16 volumio3beta-hb-xlr-02 kernel: bcm2835_v4l2: module is from the staging directory, the quality is unknown, you have been warned. Nov 11 20:55:16 volumio3beta-hb-xlr-02 kernel: bcm2835_codec: module is from the staging directory, the quality is unknown, you have been warned. Nov 11 20:55:16 volumio3beta-hb-xlr-02 kernel: bcm2835_isp: module is from the staging directory, the quality is unknown, you have been warned. Nov 11 20:55:16 volumio3beta-hb-xlr-02 kernel: bcm2835-isp bcm2835-isp: Device node output[0] registered as /dev/video13 Nov 11 20:55:16 volumio3beta-hb-xlr-02 kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video10 Nov 11 20:55:16 volumio3beta-hb-xlr-02 kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 decode Nov 11 20:55:16 volumio3beta-hb-xlr-02 kernel: bcm2835-isp bcm2835-isp: Device node capture[0] registered as /dev/video14 Nov 11 20:55:16 volumio3beta-hb-xlr-02 kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video11 Nov 11 20:55:16 volumio3beta-hb-xlr-02 kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 encode Nov 11 20:55:16 volumio3beta-hb-xlr-02 kernel: bcm2835-isp bcm2835-isp: Device node capture[1] registered as /dev/video15 Nov 11 20:55:16 volumio3beta-hb-xlr-02 kernel: bcm2835-isp bcm2835-isp: Device node stats[2] registered as /dev/video16 Nov 11 20:55:16 volumio3beta-hb-xlr-02 kernel: bcm2835-isp bcm2835-isp: Register output node 0 with media controller Nov 11 20:55:16 volumio3beta-hb-xlr-02 kernel: bcm2835-isp bcm2835-isp: Register capture node 1 with media controller Nov 11 20:55:16 volumio3beta-hb-xlr-02 kernel: bcm2835-isp bcm2835-isp: Register capture node 2 with media controller Nov 11 20:55:16 volumio3beta-hb-xlr-02 kernel: bcm2835-isp bcm2835-isp: Register capture node 3 with media controller Nov 11 20:55:16 volumio3beta-hb-xlr-02 kernel: bcm2835-isp bcm2835-isp: Loaded V4L2 bcm2835-isp Nov 11 20:55:16 volumio3beta-hb-xlr-02 systemd[1]: Found device /dev/ttyAMA0. Nov 11 20:55:16 volumio3beta-hb-xlr-02 kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video12 Nov 11 20:55:16 volumio3beta-hb-xlr-02 kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 isp Nov 11 20:55:16 volumio3beta-hb-xlr-02 kernel: snd_bcm2835: module is from the staging directory, the quality is unknown, you have been warned. Nov 11 20:55:16 volumio3beta-hb-xlr-02 kernel: bcm2835_audio bcm2835_audio: card created with 4 channels Nov 11 20:55:16 volumio3beta-hb-xlr-02 kernel: bcm2835_audio bcm2835_audio: card created with 4 channels Nov 11 20:55:17 volumio3beta-hb-xlr-02 kernel: snd-rpi-hifiberry-dacplus soc:sound: ASoC: failed to init link HiFiBerry DAC+: -517 Nov 11 20:55:17 volumio3beta-hb-xlr-02 kernel: snd-rpi-hifiberry-dacplus soc:sound: ASoC: failed to init link HiFiBerry DAC+: -517 Nov 11 20:55:17 volumio3beta-hb-xlr-02 systemd-udevd[216]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Nov 11 20:55:17 volumio3beta-hb-xlr-02 systemd-udevd[220]: Using default interface naming scheme 'v240'. Nov 11 20:55:17 volumio3beta-hb-xlr-02 kernel: cfg80211: Loading compiled-in X.509 certificates for regulatory database Nov 11 20:55:17 volumio3beta-hb-xlr-02 systemd-udevd[220]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Nov 11 20:55:17 volumio3beta-hb-xlr-02 systemd[1]: Found device /dev/mmcblk0p1. Nov 11 20:55:17 volumio3beta-hb-xlr-02 kernel: cfg80211: Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7' Nov 11 20:55:18 volumio3beta-hb-xlr-02 kernel: snd-rpi-hifiberry-dacplus soc:sound: pcm512x-hifi <-> 3f203000.i2s mapping ok Nov 11 20:55:18 volumio3beta-hb-xlr-02 kernel: 8812au: loading out-of-tree module taints kernel. Nov 11 20:55:18 volumio3beta-hb-xlr-02 kernel: 8812au: unknown parameter 'rtw_drv_log_level' ignored Nov 11 20:55:19 volumio3beta-hb-xlr-02 kernel: usbcore: registered new interface driver 8812au Nov 11 20:55:19 volumio3beta-hb-xlr-02 systemd-udevd[218]: Using default interface naming scheme 'v240'. Nov 11 20:55:19 volumio3beta-hb-xlr-02 systemd-udevd[218]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Nov 11 20:55:19 volumio3beta-hb-xlr-02 systemd[1]: Condition check resulted in SMSC9512/9514 Fast Ethernet Adapter being skipped. Nov 11 20:55:19 volumio3beta-hb-xlr-02 systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch. Nov 11 20:55:19 volumio3beta-hb-xlr-02 systemd[1]: Condition check resulted in Dispatch Password Requests to Console Directory Watch being skipped. Nov 11 20:55:19 volumio3beta-hb-xlr-02 systemd[1]: Condition check resulted in Rebuild Hardware Database being skipped. Nov 11 20:55:19 volumio3beta-hb-xlr-02 systemd[1]: Condition check resulted in Huge Pages File System being skipped. Nov 11 20:55:19 volumio3beta-hb-xlr-02 systemd[1]: Condition check resulted in FUSE Control File System being skipped. Nov 11 20:55:19 volumio3beta-hb-xlr-02 systemd[1]: Condition check resulted in Set Up Additional Binary Formats being skipped. Nov 11 20:55:19 volumio3beta-hb-xlr-02 systemd[1]: Starting File System Check on /dev/mmcblk0p1... Nov 11 20:55:19 volumio3beta-hb-xlr-02 systemd[1]: Starting Load/Save RF Kill Switch Status... Nov 11 20:55:19 volumio3beta-hb-xlr-02 systemd[1]: Started File System Check Daemon to report status. Nov 11 20:55:19 volumio3beta-hb-xlr-02 systemd[1]: Started Load/Save RF Kill Switch Status. Nov 11 20:55:19 volumio3beta-hb-xlr-02 systemd-fsck[306]: fsck.fat 4.1 (2017-01-24) Nov 11 20:55:19 volumio3beta-hb-xlr-02 systemd-fsck[306]: /dev/mmcblk0p1: 254 files, 31118/46774 clusters Nov 11 20:55:19 volumio3beta-hb-xlr-02 systemd[1]: Started File System Check on /dev/mmcblk0p1. Nov 11 20:55:19 volumio3beta-hb-xlr-02 systemd[1]: Mounting /boot... Nov 11 20:55:19 volumio3beta-hb-xlr-02 systemd[1]: Found device RTL8811AU 802.11a/b/g/n/ac WLAN Adapter. Nov 11 20:55:19 volumio3beta-hb-xlr-02 systemd[1]: Mounted /boot. Nov 11 20:55:19 volumio3beta-hb-xlr-02 systemd[1]: Reached target Local File Systems. Nov 11 20:55:19 volumio3beta-hb-xlr-02 systemd[1]: Condition check resulted in Commit a transient machine-id on disk being skipped. Nov 11 20:55:19 volumio3beta-hb-xlr-02 systemd[1]: Started ifup for eth0. Nov 11 20:55:19 volumio3beta-hb-xlr-02 systemd[1]: Starting Preprocess NFS configuration... Nov 11 20:55:19 volumio3beta-hb-xlr-02 systemd[1]: Starting Create Volatile Files and Directories... Nov 11 20:55:19 volumio3beta-hb-xlr-02 systemd[1]: Starting Raise network interfaces... Nov 11 20:55:19 volumio3beta-hb-xlr-02 systemd[1]: Starting Tell Plymouth To Write Out Runtime Data... Nov 11 20:55:19 volumio3beta-hb-xlr-02 systemd[1]: Started ifup for wlan0. Nov 11 20:55:19 volumio3beta-hb-xlr-02 systemd[1]: Received SIGRTMIN+20 from PID 223 (plymouthd). Nov 11 20:55:19 volumio3beta-hb-xlr-02 systemd[1]: nfs-config.service: Succeeded. Nov 11 20:55:19 volumio3beta-hb-xlr-02 systemd[1]: Started Preprocess NFS configuration. Nov 11 20:55:19 volumio3beta-hb-xlr-02 systemd[1]: plymouth-read-write.service: Succeeded. Nov 11 20:55:19 volumio3beta-hb-xlr-02 systemd[1]: Started Tell Plymouth To Write Out Runtime Data. Nov 11 20:55:19 volumio3beta-hb-xlr-02 systemd[1]: Condition check resulted in RPC security service for NFS server being skipped. Nov 11 20:55:19 volumio3beta-hb-xlr-02 systemd[1]: Condition check resulted in RPC security service for NFS client and server being skipped. Nov 11 20:55:19 volumio3beta-hb-xlr-02 systemd[1]: Reached target NFS client services. Nov 11 20:55:20 volumio3beta-hb-xlr-02 systemd[1]: Started Create Volatile Files and Directories. Nov 11 20:55:20 volumio3beta-hb-xlr-02 systemd[1]: Condition check resulted in Network Time Synchronization being skipped. Nov 11 20:55:20 volumio3beta-hb-xlr-02 systemd[1]: Starting RPC bind portmap service... Nov 11 20:55:20 volumio3beta-hb-xlr-02 systemd[1]: Started Entropy daemon using the HAVEGE algorithm. Nov 11 20:55:20 volumio3beta-hb-xlr-02 systemd[1]: Starting Update UTMP about System Boot/Shutdown... Nov 11 20:55:20 volumio3beta-hb-xlr-02 systemd[1]: Started Update UTMP about System Boot/Shutdown. Nov 11 20:55:20 volumio3beta-hb-xlr-02 systemd[1]: Reached target System Initialization. Nov 11 20:55:20 volumio3beta-hb-xlr-02 systemd[1]: Started Daily apt download activities. Nov 11 20:55:20 volumio3beta-hb-xlr-02 systemd[1]: Listening on mpd.socket. Nov 11 20:55:20 volumio3beta-hb-xlr-02 systemd[1]: Listening on Avahi mDNS/DNS-SD Stack Activation Socket. Nov 11 20:55:20 volumio3beta-hb-xlr-02 systemd[1]: Started Daily apt upgrade and clean activities. Nov 11 20:55:20 volumio3beta-hb-xlr-02 systemd[1]: Listening on triggerhappy.socket. Nov 11 20:55:20 volumio3beta-hb-xlr-02 systemd[1]: Listening on D-Bus System Message Bus Socket. Nov 11 20:55:20 volumio3beta-hb-xlr-02 systemd[1]: Reached target Sockets. Nov 11 20:55:20 volumio3beta-hb-xlr-02 kernel: 8021q: 802.1Q VLAN Support v1.8 Nov 11 20:55:20 volumio3beta-hb-xlr-02 systemd[1]: Reached target Basic System. Nov 11 20:55:20 volumio3beta-hb-xlr-02 systemd[1]: Condition check resulted in Copy user wpa_supplicant.conf being skipped. Nov 11 20:55:20 volumio3beta-hb-xlr-02 systemd[1]: Starting dhcpcd on all interfaces... Nov 11 20:55:20 volumio3beta-hb-xlr-02 systemd[1]: Started volumio-remote-updater.service. Nov 11 20:55:20 volumio3beta-hb-xlr-02 systemd[1]: Condition check resulted in Volumio SSH enabler being skipped. Nov 11 20:55:20 volumio3beta-hb-xlr-02 systemd[1]: Starting Wireless Services... Nov 11 20:55:20 volumio3beta-hb-xlr-02 systemd[1]: Started UPnP Renderer front-end to MPD. Nov 11 20:55:20 volumio3beta-hb-xlr-02 systemd[1]: Starting triggerhappy global hotkey daemon... Nov 11 20:55:20 volumio3beta-hb-xlr-02 systemd[1]: Started Manage Sound Card State (restore and store). Nov 11 20:55:20 volumio3beta-hb-xlr-02 dhcpcd[384]: Not running dhcpcd because /etc/network/interfaces Nov 11 20:55:20 volumio3beta-hb-xlr-02 dhcpcd[384]: defines some interfaces that will use a Nov 11 20:55:20 volumio3beta-hb-xlr-02 dhcpcd[384]: DHCP client or static address Nov 11 20:55:20 volumio3beta-hb-xlr-02 systemd[1]: Starting Save/Restore Sound Card State... Nov 11 20:55:20 volumio3beta-hb-xlr-02 alsactl[394]: alsactl 1.1.8 daemon started Nov 11 20:55:20 volumio3beta-hb-xlr-02 systemd[1]: Starting Avahi mDNS/DNS-SD Stack... Nov 11 20:55:20 volumio3beta-hb-xlr-02 thd[392]: Unable to parse trigger line: Nov 11 20:55:20 volumio3beta-hb-xlr-02 thd[392]: Unable to parse trigger line: KEY_MIN_INTERESTING 1 /usr/local/bin/volumio volume toggle Nov 11 20:55:20 volumio3beta-hb-xlr-02 thd[392]: Unable to parse trigger line: Nov 11 20:55:20 volumio3beta-hb-xlr-02 systemd[1]: Started D-Bus System Message Bus. Nov 11 20:55:20 volumio3beta-hb-xlr-02 systemd[1]: Starting WPA supplicant... Nov 11 20:55:20 volumio3beta-hb-xlr-02 systemd[1]: Starting Login Service... Nov 11 20:55:20 volumio3beta-hb-xlr-02 systemd[1]: Condition check resulted in Configure Bluetooth Modems connected by UART being skipped. Nov 11 20:55:20 volumio3beta-hb-xlr-02 systemd[1]: Started Daily Cleanup of Temporary Directories. Nov 11 20:55:20 volumio3beta-hb-xlr-02 systemd[1]: Reached target Timers. Nov 11 20:55:20 volumio3beta-hb-xlr-02 systemd[1]: Condition check resulted in getty on tty2-tty6 if dbus and logind are not available being skipped. Nov 11 20:55:20 volumio3beta-hb-xlr-02 kernel: random: crng init done Nov 11 20:55:20 volumio3beta-hb-xlr-02 kernel: random: 7 urandom warning(s) missed due to ratelimiting Nov 11 20:55:20 volumio3beta-hb-xlr-02 systemd[1]: Started Volumio Iptables Module. Nov 11 20:55:20 volumio3beta-hb-xlr-02 systemd[1]: dhcpcd.service: Control process exited, code=exited, status=6/NOTCONFIGURED Nov 11 20:55:20 volumio3beta-hb-xlr-02 systemd[1]: dhcpcd.service: Failed with result 'exit-code'. Nov 11 20:55:20 volumio3beta-hb-xlr-02 systemd[1]: Failed to start dhcpcd on all interfaces. Nov 11 20:55:20 volumio3beta-hb-xlr-02 systemd[1]: Started Save/Restore Sound Card State. Nov 11 20:55:20 volumio3beta-hb-xlr-02 systemd[1]: Reached target Sound Card. Nov 11 20:55:21 volumio3beta-hb-xlr-02 avahi-daemon[397]: Found user 'avahi' (UID 110) and group 'avahi' (GID 113). Nov 11 20:55:21 volumio3beta-hb-xlr-02 avahi-daemon[397]: Successfully dropped root privileges. Nov 11 20:55:21 volumio3beta-hb-xlr-02 avahi-daemon[397]: avahi-daemon 0.7 starting up. Nov 11 20:55:21 volumio3beta-hb-xlr-02 thd[392]: Found socket passed from systemd Nov 11 20:55:21 volumio3beta-hb-xlr-02 kernel: [phydm_la_set_buff_mode] Warning! Nov 11 20:55:21 volumio3beta-hb-xlr-02 kernel: start_addr=(0x0), end_addr=(0x0), buffer_size=(0x0), smp_number_max=(0) Nov 11 20:55:21 volumio3beta-hb-xlr-02 systemd-logind[405]: New seat seat0. Nov 11 20:55:21 volumio3beta-hb-xlr-02 sh[314]: eth0: waiting for carrier Nov 11 20:55:21 volumio3beta-hb-xlr-02 kernel: smsc95xx 1-1.1:1.0 eth0: hardware isn't capable of remote wakeup Nov 11 20:55:21 volumio3beta-hb-xlr-02 sh[314]: eth0: carrier acquired Nov 11 20:55:21 volumio3beta-hb-xlr-02 dhcpcd[365]: eth0: waiting for carrier Nov 11 20:55:21 volumio3beta-hb-xlr-02 dhcpcd[365]: eth0: carrier acquired Nov 11 20:55:21 volumio3beta-hb-xlr-02 sh[314]: DUID 00:01:00:01:28:f3:25:3e:b8:27:eb:43:bd:38 Nov 11 20:55:21 volumio3beta-hb-xlr-02 sh[314]: eth0: IAID eb:43:bd:38 Nov 11 20:55:21 volumio3beta-hb-xlr-02 dhcpcd[365]: DUID 00:01:00:01:28:f3:25:3e:b8:27:eb:43:bd:38 Nov 11 20:55:21 volumio3beta-hb-xlr-02 dhcpcd[365]: eth0: IAID eb:43:bd:38 Nov 11 20:55:21 volumio3beta-hb-xlr-02 sh[314]: eth0: adding address fe80::ba1c:4ec3:20ba:cad4 Nov 11 20:55:21 volumio3beta-hb-xlr-02 sh[314]: ipv6_addaddr1: Permission denied Nov 11 20:55:21 volumio3beta-hb-xlr-02 dhcpcd[365]: eth0: adding address fe80::ba1c:4ec3:20ba:cad4 Nov 11 20:55:21 volumio3beta-hb-xlr-02 dhcpcd[365]: ipv6_addaddr1: Permission denied Nov 11 20:55:21 volumio3beta-hb-xlr-02 systemd[1]: Started triggerhappy global hotkey daemon. Nov 11 20:55:21 volumio3beta-hb-xlr-02 systemd[1]: Started RPC bind portmap service. Nov 11 20:55:21 volumio3beta-hb-xlr-02 avahi-daemon[397]: Successfully called chroot(). Nov 11 20:55:21 volumio3beta-hb-xlr-02 avahi-daemon[397]: Successfully dropped remaining capabilities. Nov 11 20:55:21 volumio3beta-hb-xlr-02 avahi-daemon[397]: Loading service file /services/volumio.service. Nov 11 20:55:21 volumio3beta-hb-xlr-02 avahi-daemon[397]: Network interface enumeration completed. Nov 11 20:55:21 volumio3beta-hb-xlr-02 avahi-daemon[397]: Server startup complete. Host name is volumio3beta-hb-xlr-02.local. Local service cookie is 2017179803. Nov 11 20:55:21 volumio3beta-hb-xlr-02 avahi-daemon[397]: Service "Volumio3Beta-hb-xlr-02" (/services/volumio.service) successfully established. Nov 11 20:55:21 volumio3beta-hb-xlr-02 systemd[1]: Started Login Service. Nov 11 20:55:21 volumio3beta-hb-xlr-02 systemd[1]: Started Avahi mDNS/DNS-SD Stack. Nov 11 20:55:21 volumio3beta-hb-xlr-02 systemd[1]: Reached target RPC Port Mapper. Nov 11 20:55:21 volumio3beta-hb-xlr-02 systemd[1]: Reached target Remote File Systems (Pre). Nov 11 20:55:21 volumio3beta-hb-xlr-02 systemd[1]: Reached target Remote File Systems. Nov 11 20:55:21 volumio3beta-hb-xlr-02 systemd[1]: Starting LSB: Load kernel modules needed to enable cpufreq scaling... Nov 11 20:55:21 volumio3beta-hb-xlr-02 sh[321]: wlan0=wlan0 Nov 11 20:55:21 volumio3beta-hb-xlr-02 sh[314]: eth0: soliciting a DHCP lease Nov 11 20:55:21 volumio3beta-hb-xlr-02 dhcpcd[365]: eth0: soliciting a DHCP lease Nov 11 20:55:21 volumio3beta-hb-xlr-02 systemd[1]: Started Raise network interfaces. Nov 11 20:55:22 volumio3beta-hb-xlr-02 sh[314]: eth0: soliciting an IPv6 router Nov 11 20:55:22 volumio3beta-hb-xlr-02 dhcpcd[365]: eth0: soliciting an IPv6 router Nov 11 20:55:22 volumio3beta-hb-xlr-02 loadcpufreq[455]: Loading cpufreq kernel modules...done (none). Nov 11 20:55:22 volumio3beta-hb-xlr-02 systemd[1]: Started LSB: Load kernel modules needed to enable cpufreq scaling. Nov 11 20:55:22 volumio3beta-hb-xlr-02 systemd[1]: Starting LSB: set CPUFreq kernel parameters... Nov 11 20:55:22 volumio3beta-hb-xlr-02 sh[314]: eth0: carrier lost Nov 11 20:55:22 volumio3beta-hb-xlr-02 dhcpcd[365]: eth0: carrier lost Nov 11 20:55:22 volumio3beta-hb-xlr-02 systemd[1]: Started WPA supplicant. Nov 11 20:55:22 volumio3beta-hb-xlr-02 systemd[1]: Reached target Network. Nov 11 20:55:22 volumio3beta-hb-xlr-02 wpa_supplicant[403]: Successfully initialized wpa_supplicant Nov 11 20:55:22 volumio3beta-hb-xlr-02 systemd[1]: Reached target Network is Online. Nov 11 20:55:22 volumio3beta-hb-xlr-02 systemd[1]: Starting LSB: Brings up/down network automatically... Nov 11 20:55:22 volumio3beta-hb-xlr-02 systemd[1]: Starting Samba NMB Daemon... Nov 11 20:55:22 volumio3beta-hb-xlr-02 systemd[1]: Starting Network Time Service... Nov 11 20:55:22 volumio3beta-hb-xlr-02 systemd[1]: Condition check resulted in fast remote file copy program daemon being skipped. Nov 11 20:55:22 volumio3beta-hb-xlr-02 systemd[1]: Starting Music Player Daemon... Nov 11 20:55:22 volumio3beta-hb-xlr-02 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Nov 11 20:55:22 volumio3beta-hb-xlr-02 systemd[1]: Starting Permit User Sessions... Nov 11 20:55:22 volumio3beta-hb-xlr-02 systemd[1]: Starting /etc/rc.local Compatibility... Nov 11 20:55:22 volumio3beta-hb-xlr-02 cpufrequtils[499]: CPUFreq Utilities: Setting performance CPUFreq governor...CPU0...CPU1...CPU2...CPU3...done. Nov 11 20:55:22 volumio3beta-hb-xlr-02 sh[314]: eth0: carrier acquired Nov 11 20:55:22 volumio3beta-hb-xlr-02 dhcpcd[365]: eth0: carrier acquired Nov 11 20:55:23 volumio3beta-hb-xlr-02 kernel: smsc95xx 1-1.1:1.0 eth0: link up, 100Mbps, full-duplex, lpa 0xC5E1 Nov 11 20:55:23 volumio3beta-hb-xlr-02 kernel: smsc95xx 1-1.1:1.0 eth0: link up, 100Mbps, full-duplex, lpa 0xC5E1 Nov 11 20:55:23 volumio3beta-hb-xlr-02 systemd[1]: Started LSB: set CPUFreq kernel parameters. Nov 11 20:55:23 volumio3beta-hb-xlr-02 sh[314]: eth0: IAID eb:43:bd:38 Nov 11 20:55:23 volumio3beta-hb-xlr-02 dhcpcd[365]: eth0: IAID eb:43:bd:38 Nov 11 20:55:23 volumio3beta-hb-xlr-02 systemd[1]: Started /etc/rc.local Compatibility. Nov 11 20:55:23 volumio3beta-hb-xlr-02 systemd[1]: Started Permit User Sessions. Nov 11 20:55:23 volumio3beta-hb-xlr-02 systemd[1]: Starting Terminate Plymouth Boot Screen... Nov 11 20:55:23 volumio3beta-hb-xlr-02 systemd[1]: Starting Hold until boot process finishes up... Nov 11 20:55:23 volumio3beta-hb-xlr-02 systemd[1]: Received SIGRTMIN+21 from PID 223 (plymouthd). Nov 11 20:55:23 volumio3beta-hb-xlr-02 sh[314]: eth0: soliciting a DHCP lease Nov 11 20:55:23 volumio3beta-hb-xlr-02 dhcpcd[365]: eth0: soliciting a DHCP lease Nov 11 20:55:23 volumio3beta-hb-xlr-02 systemd[1]: plymouth-quit.service: Succeeded. Nov 11 20:55:23 volumio3beta-hb-xlr-02 systemd[1]: Started Terminate Plymouth Boot Screen. Nov 11 20:55:23 volumio3beta-hb-xlr-02 systemd[1]: plymouth-start.service: Succeeded. Nov 11 20:55:23 volumio3beta-hb-xlr-02 systemd[1]: plymouth-quit-wait.service: Succeeded. Nov 11 20:55:23 volumio3beta-hb-xlr-02 systemd[1]: Started Hold until boot process finishes up. Nov 11 20:55:23 volumio3beta-hb-xlr-02 ifplugd(eth0)[564]: ifplugd 0.28 initializing. Nov 11 20:55:23 volumio3beta-hb-xlr-02 ifplugd(eth0)[564]: Using interface eth0/B8:27:EB:43:BD:38 with driver (version: 22-Aug-2005) Nov 11 20:55:23 volumio3beta-hb-xlr-02 systemd[1]: Received SIGRTMIN+21 from PID 223 (n/a). Nov 11 20:55:23 volumio3beta-hb-xlr-02 sh[314]: eth0: offered 192.168.178.172 from 192.168.178.1 Nov 11 20:55:23 volumio3beta-hb-xlr-02 sh[314]: eth0: probing address 192.168.178.172/24 Nov 11 20:55:23 volumio3beta-hb-xlr-02 dhcpcd[365]: eth0: offered 192.168.178.172 from 192.168.178.1 Nov 11 20:55:23 volumio3beta-hb-xlr-02 dhcpcd[365]: eth0: probing address 192.168.178.172/24 Nov 11 20:55:23 volumio3beta-hb-xlr-02 ifplugd(eth0)[564]: Using detection mode: SIOCETHTOOL Nov 11 20:55:23 volumio3beta-hb-xlr-02 ifplugd(eth0)[564]: Initialization complete, link beat detected. Nov 11 20:55:23 volumio3beta-hb-xlr-02 systemd[1]: Started Serial Getty on ttyAMA0. Nov 11 20:55:23 volumio3beta-hb-xlr-02 systemd[1]: Started Getty on tty1. Nov 11 20:55:23 volumio3beta-hb-xlr-02 systemd[1]: Reached target Login Prompts. Nov 11 20:55:23 volumio3beta-hb-xlr-02 systemd[1]: iptables.service: Succeeded. Nov 11 20:55:23 volumio3beta-hb-xlr-02 volumio-remote-updater[386]: Error: No active session Nov 11 20:55:23 volumio3beta-hb-xlr-02 ifplugd(eth0)[564]: Executing '/etc/ifplugd/ifplugd.action eth0 up'. Nov 11 20:55:23 volumio3beta-hb-xlr-02 volumio-remote-updater[386]: [2021-11-11 20:55:23] [info] asio async_connect error: system:111 (Connection refused) Nov 11 20:55:23 volumio3beta-hb-xlr-02 volumio-remote-updater[386]: [2021-11-11 20:55:23] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Nov 11 20:55:23 volumio3beta-hb-xlr-02 volumio-remote-updater[386]: [2021-11-11 20:55:23] [error] handle_connect error: Underlying Transport Error Nov 11 20:55:23 volumio3beta-hb-xlr-02 ifplugd(eth0)[564]: client: ifup: waiting for lock on /run/network/ifstate.eth0 Nov 11 20:55:23 volumio3beta-hb-xlr-02 ntpd[526]: ntpd 4.2.8p12@1.3728-o (1): Starting Nov 11 20:55:23 volumio3beta-hb-xlr-02 ntpd[526]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 103:103 Nov 11 20:55:23 volumio3beta-hb-xlr-02 haveged[358]: haveged: ver: 1.9.1; arch: generic; vend: ; build: (gcc 8.2.0 CTV); collect: 128K Nov 11 20:55:23 volumio3beta-hb-xlr-02 haveged[358]: haveged: cpu: (VC); data: 16K (D); inst: 16K (D); idx: 11/40; sz: 14768/63488 Nov 11 20:55:23 volumio3beta-hb-xlr-02 haveged[358]: haveged: tot tests(BA8): A:1/1 B:1/1 continuous tests(B): last entropy estimate 8.00044 Nov 11 20:55:23 volumio3beta-hb-xlr-02 haveged[358]: haveged: fills: 0, generated: 0 Nov 11 20:55:23 volumio3beta-hb-xlr-02 systemd[1]: Started Network Time Service. Nov 11 20:55:23 volumio3beta-hb-xlr-02 ntpd[578]: proto: precision = 1.145 usec (-20) Nov 11 20:55:23 volumio3beta-hb-xlr-02 ntpd[578]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Nov 11 20:55:23 volumio3beta-hb-xlr-02 ntpd[578]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2022-06-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Nov 11 20:55:23 volumio3beta-hb-xlr-02 ntpd[578]: Listen and drop on 0 v6wildcard [::]:123 Nov 11 20:55:23 volumio3beta-hb-xlr-02 ntpd[578]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Nov 11 20:55:23 volumio3beta-hb-xlr-02 ntpd[578]: Listen normally on 2 lo 127.0.0.1:123 Nov 11 20:55:23 volumio3beta-hb-xlr-02 ntpd[578]: Listening on routing socket on fd #19 for interface updates Nov 11 20:55:23 volumio3beta-hb-xlr-02 ntpd[578]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Nov 11 20:55:23 volumio3beta-hb-xlr-02 ntpd[578]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Nov 11 20:55:23 volumio3beta-hb-xlr-02 sh[314]: eth0: soliciting an IPv6 router Nov 11 20:55:23 volumio3beta-hb-xlr-02 dhcpcd[365]: eth0: soliciting an IPv6 router Nov 11 20:55:23 volumio3beta-hb-xlr-02 volumio[391]: Could not open config: /tmp/upmpdcli.conf Nov 11 20:55:24 volumio3beta-hb-xlr-02 systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Nov 11 20:55:24 volumio3beta-hb-xlr-02 systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Nov 11 20:55:24 volumio3beta-hb-xlr-02 systemd[1]: systemd-rfkill.service: Succeeded. Nov 11 20:55:24 volumio3beta-hb-xlr-02 ntpd[578]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Nov 11 20:55:25 volumio3beta-hb-xlr-02 nmbd[543]: [2021/11/11 20:55:25.258159, 0] ../source3/nmbd/asyncdns.c:158(start_async_dns) Nov 11 20:55:25 volumio3beta-hb-xlr-02 nmbd[543]: started asyncdns process 582 Nov 11 20:55:25 volumio3beta-hb-xlr-02 nmbd[543]: [2021/11/11 20:55:25.266070, 0] ../lib/util/become_daemon.c:149(daemon_status) Nov 11 20:55:25 volumio3beta-hb-xlr-02 nmbd[543]: daemon_status: STATUS=daemon 'nmbd' : No local IPv4 non-loopback interfaces available, waiting for interface ... Nov 11 20:55:25 volumio3beta-hb-xlr-02 nmbd[543]: [2021/11/11 20:55:25.266509, 0] ../source3/nmbd/nmbd_subnetdb.c:254(create_subnets) Nov 11 20:55:25 volumio3beta-hb-xlr-02 nmbd[543]: NOTE: NetBIOS name resolution is not supported for Internet Protocol Version 6 (IPv6). Nov 11 20:55:25 volumio3beta-hb-xlr-02 ntpd[578]: error resolving pool 1.debian.pool.ntp.org: System error (-11) Nov 11 20:55:26 volumio3beta-hb-xlr-02 ntpd[578]: error resolving pool 2.debian.pool.ntp.org: System error (-11) Nov 11 20:55:27 volumio3beta-hb-xlr-02 ntpd[578]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Nov 11 20:55:27 volumio3beta-hb-xlr-02 wireless.js[387]: WIRELESS DAEMON: start Nov 11 20:55:27 volumio3beta-hb-xlr-02 wireless.js[387]: WIRELESS: Loaded configuration Nov 11 20:55:27 volumio3beta-hb-xlr-02 wireless.js[387]: Cleaning previous... Nov 11 20:55:28 volumio3beta-hb-xlr-02 sh[314]: eth0: leased 192.168.178.172 for 864000 seconds Nov 11 20:55:28 volumio3beta-hb-xlr-02 dhcpcd[365]: eth0: leased 192.168.178.172 for 864000 seconds Nov 11 20:55:28 volumio3beta-hb-xlr-02 sh[314]: eth0: adding route to 192.168.178.0/24 Nov 11 20:55:28 volumio3beta-hb-xlr-02 sh[314]: eth0: adding default route via 192.168.178.1 Nov 11 20:55:28 volumio3beta-hb-xlr-02 dhcpcd[365]: eth0: adding route to 192.168.178.0/24 Nov 11 20:55:28 volumio3beta-hb-xlr-02 dhcpcd[365]: eth0: adding default route via 192.168.178.1 Nov 11 20:55:28 volumio3beta-hb-xlr-02 avahi-daemon[397]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.178.172. Nov 11 20:55:28 volumio3beta-hb-xlr-02 avahi-daemon[397]: New relevant interface eth0.IPv4 for mDNS. Nov 11 20:55:28 volumio3beta-hb-xlr-02 avahi-daemon[397]: Registering new address record for 192.168.178.172 on eth0.IPv4. Nov 11 20:55:28 volumio3beta-hb-xlr-02 sudo[592]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Nov 11 20:55:28 volumio3beta-hb-xlr-02 sudo[592]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 11 20:55:28 volumio3beta-hb-xlr-02 sudo[592]: pam_unix(sudo:session): session closed for user root Nov 11 20:55:28 volumio3beta-hb-xlr-02 sudo[598]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Nov 11 20:55:28 volumio3beta-hb-xlr-02 sudo[598]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 11 20:55:28 volumio3beta-hb-xlr-02 sudo[598]: pam_unix(sudo:session): session closed for user root Nov 11 20:55:28 volumio3beta-hb-xlr-02 volumio-remote-updater[386]: [2021-11-11 20:55:28] [info] asio async_connect error: system:111 (Connection refused) Nov 11 20:55:28 volumio3beta-hb-xlr-02 volumio-remote-updater[386]: [2021-11-11 20:55:28] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Nov 11 20:55:28 volumio3beta-hb-xlr-02 volumio-remote-updater[386]: [2021-11-11 20:55:28] [error] handle_connect error: Underlying Transport Error Nov 11 20:55:28 volumio3beta-hb-xlr-02 sh[314]: forked to background, child pid 620 Nov 11 20:55:28 volumio3beta-hb-xlr-02 dhcpcd[365]: forked to background, child pid 620 Nov 11 20:55:28 volumio3beta-hb-xlr-02 wireless.js[387]: Stopped aP Nov 11 20:55:28 volumio3beta-hb-xlr-02 sudo[644]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Nov 11 20:55:28 volumio3beta-hb-xlr-02 sudo[644]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 11 20:55:28 volumio3beta-hb-xlr-02 sudo[644]: pam_unix(sudo:session): session closed for user root Nov 11 20:55:28 volumio3beta-hb-xlr-02 sudo[649]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Nov 11 20:55:28 volumio3beta-hb-xlr-02 sudo[649]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 11 20:55:28 volumio3beta-hb-xlr-02 ifplugd(eth0)[564]: client: ifup: interface eth0 already configured Nov 11 20:55:28 volumio3beta-hb-xlr-02 sh[314]: eth0=eth0 Nov 11 20:55:28 volumio3beta-hb-xlr-02 sudo[649]: pam_unix(sudo:session): session closed for user root Nov 11 20:55:28 volumio3beta-hb-xlr-02 avahi-daemon[397]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.211.1. Nov 11 20:55:28 volumio3beta-hb-xlr-02 avahi-daemon[397]: New relevant interface wlan0.IPv4 for mDNS. Nov 11 20:55:28 volumio3beta-hb-xlr-02 avahi-daemon[397]: Registering new address record for 192.168.211.1 on wlan0.IPv4. Nov 11 20:55:28 volumio3beta-hb-xlr-02 ifplugd(eth0)[564]: Program executed successfully. Nov 11 20:55:28 volumio3beta-hb-xlr-02 ifplugd[515]: Network Interface Plugging Daemon...start eth0...done. Nov 11 20:55:28 volumio3beta-hb-xlr-02 mpd[581]: Nov 11 20:55 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Nov 11 20:55:28 volumio3beta-hb-xlr-02 systemd[1]: Started LSB: Brings up/down network automatically. Nov 11 20:55:28 volumio3beta-hb-xlr-02 systemd[1]: Starting dnsmasq - A lightweight DHCP and caching DNS server... Nov 11 20:55:28 volumio3beta-hb-xlr-02 systemd[1]: Starting Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator... Nov 11 20:55:28 volumio3beta-hb-xlr-02 dnsmasq[659]: dnsmasq: syntax check OK. Nov 11 20:55:29 volumio3beta-hb-xlr-02 hostapd[660]: Configuration file: /etc/hostapd/hostapd.conf Nov 11 20:55:29 volumio3beta-hb-xlr-02 hostapd[660]: Using interface wlan0 with hwaddr 3c:49:37:17:69:71 and ssid "Volumio" Nov 11 20:55:29 volumio3beta-hb-xlr-02 dnsmasq[666]: started, version 2.80 cachesize 150 Nov 11 20:55:29 volumio3beta-hb-xlr-02 dnsmasq[666]: compile time options: IPv6 GNU-getopt DBus i18n IDN DHCP DHCPv6 no-Lua TFTP conntrack ipset auth DNSSEC loop-detect inotify dumpfile Nov 11 20:55:29 volumio3beta-hb-xlr-02 dnsmasq-dhcp[666]: DHCP, IP range 192.168.211.100 -- 192.168.211.200, lease time 1d Nov 11 20:55:29 volumio3beta-hb-xlr-02 dnsmasq[666]: reading /etc/resolv.conf Nov 11 20:55:29 volumio3beta-hb-xlr-02 dnsmasq[666]: using nameserver 192.168.178.1#53 Nov 11 20:55:29 volumio3beta-hb-xlr-02 dnsmasq[666]: using nameserver 208.67.222.222#53 Nov 11 20:55:29 volumio3beta-hb-xlr-02 dnsmasq[666]: using nameserver 208.67.220.220#53 Nov 11 20:55:29 volumio3beta-hb-xlr-02 dnsmasq[666]: read /etc/hosts - 1 addresses Nov 11 20:55:29 volumio3beta-hb-xlr-02 hostapd[660]: wlan0: interface state UNINITIALIZED->ENABLED Nov 11 20:55:29 volumio3beta-hb-xlr-02 hostapd[660]: wlan0: AP-ENABLED Nov 11 20:55:29 volumio3beta-hb-xlr-02 systemd[1]: Started Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator. Nov 11 20:55:29 volumio3beta-hb-xlr-02 systemd[1]: Started dnsmasq - A lightweight DHCP and caching DNS server. Nov 11 20:55:29 volumio3beta-hb-xlr-02 systemd[1]: Reached target Host and Network Name Lookups. Nov 11 20:55:29 volumio3beta-hb-xlr-02 systemd[1]: wireless.service: Succeeded. Nov 11 20:55:29 volumio3beta-hb-xlr-02 systemd[1]: Started Wireless Services. Nov 11 20:55:29 volumio3beta-hb-xlr-02 systemd[1]: Started Volumio Backend Module. Nov 11 20:55:29 volumio3beta-hb-xlr-02 systemd[1]: Started Volumio Streaming Daemon. Nov 11 20:55:29 volumio3beta-hb-xlr-02 mpd[581]: Nov 11 20:55 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Nov 11 20:55:29 volumio3beta-hb-xlr-02 mpd[581]: Nov 11 20:55 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Nov 11 20:55:29 volumio3beta-hb-xlr-02 systemd[1]: Started Music Player Daemon. Nov 11 20:55:29 volumio3beta-hb-xlr-02 volumio-streaming-daemon[675]: ############################ Nov 11 20:55:29 volumio3beta-hb-xlr-02 volumio-streaming-daemon[675]: # Volumio Streaming Daemon # Nov 11 20:55:29 volumio3beta-hb-xlr-02 volumio-streaming-daemon[675]: # Running on port 7777 # Nov 11 20:55:29 volumio3beta-hb-xlr-02 volumio-streaming-daemon[675]: ############################ Nov 11 20:55:29 volumio3beta-hb-xlr-02 volumio-streaming-daemon[675]: Environment: production Nov 11 20:55:30 volumio3beta-hb-xlr-02 nmbd[543]: [2021/11/11 20:55:30.289715, 0] ../lib/util/become_daemon.c:138(daemon_ready) Nov 11 20:55:30 volumio3beta-hb-xlr-02 nmbd[543]: daemon_ready: STATUS=daemon 'nmbd' finished starting up and ready to serve connections Nov 11 20:55:30 volumio3beta-hb-xlr-02 systemd[1]: Started Samba NMB Daemon. Nov 11 20:55:30 volumio3beta-hb-xlr-02 systemd[1]: Starting Samba Winbind Daemon... Nov 11 20:55:30 volumio3beta-hb-xlr-02 ntpd[578]: Listen normally on 3 eth0 192.168.178.172:123 Nov 11 20:55:30 volumio3beta-hb-xlr-02 ntpd[578]: Listen normally on 4 wlan0 192.168.211.1:123 Nov 11 20:55:30 volumio3beta-hb-xlr-02 ntpd[578]: new interface(s) found: waking up resolver Nov 11 20:55:30 volumio3beta-hb-xlr-02 winbindd[689]: [2021/11/11 20:55:30.873221, 0] ../source3/winbindd/winbindd_cache.c:3160(initialize_winbindd_cache) Nov 11 20:55:30 volumio3beta-hb-xlr-02 winbindd[689]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Nov 11 20:55:30 volumio3beta-hb-xlr-02 winbindd[689]: [2021/11/11 20:55:30.971868, 0] ../lib/util/become_daemon.c:138(daemon_ready) Nov 11 20:55:30 volumio3beta-hb-xlr-02 systemd[1]: Started Samba Winbind Daemon. Nov 11 20:55:30 volumio3beta-hb-xlr-02 winbindd[689]: daemon_ready: STATUS=daemon 'winbindd' finished starting up and ready to serve connections Nov 11 20:55:30 volumio3beta-hb-xlr-02 systemd[1]: Starting Samba SMB Daemon... Nov 11 20:55:32 volumio3beta-hb-xlr-02 smbd[694]: [2021/11/11 20:55:32.061012, 0] ../lib/util/become_daemon.c:138(daemon_ready) Nov 11 20:55:32 volumio3beta-hb-xlr-02 smbd[694]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections Nov 11 20:55:32 volumio3beta-hb-xlr-02 systemd[1]: Started Samba SMB Daemon. Nov 11 20:55:32 volumio3beta-hb-xlr-02 systemd[1]: Reached target Multi-User System. Nov 11 20:55:32 volumio3beta-hb-xlr-02 systemd[1]: Reached target Graphical Interface. Nov 11 20:55:32 volumio3beta-hb-xlr-02 systemd[1]: Starting Update UTMP about System Runlevel Changes... Nov 11 20:55:32 volumio3beta-hb-xlr-02 systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Nov 11 20:55:32 volumio3beta-hb-xlr-02 systemd[1]: Started Update UTMP about System Runlevel Changes. Nov 11 20:55:32 volumio3beta-hb-xlr-02 systemd[1]: Startup finished in 12.977s (kernel) + 19.549s (userspace) = 32.526s. Nov 11 20:55:35 volumio3beta-hb-xlr-02 volumio[674]: info: ------------------------------------------- Nov 11 20:55:35 volumio3beta-hb-xlr-02 volumio[674]: info: ----- Volumio3 ---- Nov 11 20:55:35 volumio3beta-hb-xlr-02 volumio[674]: info: ------------------------------------------- Nov 11 20:55:35 volumio3beta-hb-xlr-02 volumio[674]: info: ----- System startup ---- Nov 11 20:55:35 volumio3beta-hb-xlr-02 volumio[674]: info: ------------------------------------------- Nov 11 20:55:35 volumio3beta-hb-xlr-02 volumio-remote-updater[386]: [2021-11-11 20:55:35] [connect] Successful connection Nov 11 20:55:37 volumio3beta-hb-xlr-02 volumio[674]: info: MYVOLUMIO Environment detected Nov 11 20:55:37 volumio3beta-hb-xlr-02 volumio[674]: error: Could not read plugin package at /data/plugins/music_service/airplay_emulation: Error: /data/plugins/music_service/airplay_emulation/package.json: ENOENT: no such file or directory, open '/data/plugins/music_service/airplay_emulation/package.json' Nov 11 20:55:37 volumio3beta-hb-xlr-02 volumio[674]: error: Could not read plugin package at /myvolumio/plugins/music_service/airplay_emulation: Error: /myvolumio/plugins/music_service/airplay_emulation/package.json: ENOENT: no such file or directory, open '/myvolumio/plugins/music_service/airplay_emulation/package.json' Nov 11 20:55:37 volumio3beta-hb-xlr-02 volumio[674]: error: Could not read plugin package at /data/plugins/music_service/inputs: Error: /data/plugins/music_service/inputs/package.json: ENOENT: no such file or directory, open '/data/plugins/music_service/inputs/package.json' Nov 11 20:55:37 volumio3beta-hb-xlr-02 volumio[674]: error: Could not read plugin package at /myvolumio/plugins/music_service/inputs: Error: /myvolumio/plugins/music_service/inputs/package.json: ENOENT: no such file or directory, open '/myvolumio/plugins/music_service/inputs/package.json' Nov 11 20:55:37 volumio3beta-hb-xlr-02 volumio[674]: error: Could not read plugin package at /data/plugins/music_service/mpd: Error: /data/plugins/music_service/mpd/package.json: ENOENT: no such file or directory, open '/data/plugins/music_service/mpd/package.json' Nov 11 20:55:37 volumio3beta-hb-xlr-02 volumio[674]: error: Could not read plugin package at /myvolumio/plugins/music_service/mpd: Error: /myvolumio/plugins/music_service/mpd/package.json: ENOENT: no such file or directory, open '/myvolumio/plugins/music_service/mpd/package.json' Nov 11 20:55:37 volumio3beta-hb-xlr-02 volumio[674]: error: Could not read plugin package at /data/plugins/music_service/webradio: Error: /data/plugins/music_service/webradio/package.json: ENOENT: no such file or directory, open '/data/plugins/music_service/webradio/package.json' Nov 11 20:55:37 volumio3beta-hb-xlr-02 volumio[674]: error: Could not read plugin package at /myvolumio/plugins/music_service/webradio: Error: /myvolumio/plugins/music_service/webradio/package.json: ENOENT: no such file or directory, open '/myvolumio/plugins/music_service/webradio/package.json' Nov 11 20:55:37 volumio3beta-hb-xlr-02 volumio[674]: error: Could not read plugin package at /data/plugins/music_service/example_plugin: Error: /data/plugins/music_service/example_plugin/package.json: ENOENT: no such file or directory, open '/data/plugins/music_service/example_plugin/package.json' Nov 11 20:55:37 volumio3beta-hb-xlr-02 volumio[674]: error: Could not read plugin package at /myvolumio/plugins/music_service/example_plugin: Error: /myvolumio/plugins/music_service/example_plugin/package.json: ENOENT: no such file or directory, open '/myvolumio/plugins/music_service/example_plugin/package.json' Nov 11 20:55:37 volumio3beta-hb-xlr-02 volumio[674]: error: Could not read plugin package at /data/plugins/music_service/last_100: Error: /data/plugins/music_service/last_100/package.json: ENOENT: no such file or directory, open '/data/plugins/music_service/last_100/package.json' Nov 11 20:55:37 volumio3beta-hb-xlr-02 volumio[674]: error: Could not read plugin package at /myvolumio/plugins/music_service/last_100: Error: /myvolumio/plugins/music_service/last_100/package.json: ENOENT: no such file or directory, open '/myvolumio/plugins/music_service/last_100/package.json' Nov 11 20:55:37 volumio3beta-hb-xlr-02 volumio[674]: error: Could not read plugin package at /data/plugins/music_service/upnp_browser: Error: /data/plugins/music_service/upnp_browser/package.json: ENOENT: no such file or directory, open '/data/plugins/music_service/upnp_browser/package.json' Nov 11 20:55:37 volumio3beta-hb-xlr-02 volumio[674]: error: Could not read plugin package at /myvolumio/plugins/music_service/upnp_browser: Error: /myvolumio/plugins/music_service/upnp_browser/package.json: ENOENT: no such file or directory, open '/myvolumio/plugins/music_service/upnp_browser/package.json' Nov 11 20:55:37 volumio3beta-hb-xlr-02 volumio[674]: error: Could not read plugin package at /volumio/app/plugins/music_service/streaming_services: Error: /volumio/app/plugins/music_service/streaming_services/package.json: ENOENT: no such file or directory, open '/volumio/app/plugins/music_service/streaming_services/package.json' Nov 11 20:55:37 volumio3beta-hb-xlr-02 volumio[674]: error: Could not read plugin package at /data/plugins/music_service/streaming_services: Error: /data/plugins/music_service/streaming_services/package.json: ENOENT: no such file or directory, open '/data/plugins/music_service/streaming_services/package.json' Nov 11 20:55:37 volumio3beta-hb-xlr-02 volumio[674]: error: Could not read plugin package at /volumio/app/plugins/music_service/cd_controller: Error: /volumio/app/plugins/music_service/cd_controller/package.json: ENOENT: no such file or directory, open '/volumio/app/plugins/music_service/cd_controller/package.json' Nov 11 20:55:37 volumio3beta-hb-xlr-02 volumio[674]: error: Could not read plugin package at /data/plugins/music_service/cd_controller: Error: /data/plugins/music_service/cd_controller/package.json: ENOENT: no such file or directory, open '/data/plugins/music_service/cd_controller/package.json' Nov 11 20:55:37 volumio3beta-hb-xlr-02 volumio[674]: error: Could not read plugin package at /volumio/app/plugins/music_service/smart_inputs: Error: /volumio/app/plugins/music_service/smart_inputs/package.json: ENOENT: no such file or directory, open '/volumio/app/plugins/music_service/smart_inputs/package.json' Nov 11 20:55:37 volumio3beta-hb-xlr-02 volumio[674]: error: Could not read plugin package at /data/plugins/music_service/smart_inputs: Error: /data/plugins/music_service/smart_inputs/package.json: ENOENT: no such file or directory, open '/data/plugins/music_service/smart_inputs/package.json' Nov 11 20:55:37 volumio3beta-hb-xlr-02 volumio[674]: error: Could not read plugin package at /volumio/app/plugins/music_service/hi_res_audio: Error: /volumio/app/plugins/music_service/hi_res_audio/package.json: ENOENT: no such file or directory, open '/volumio/app/plugins/music_service/hi_res_audio/package.json' Nov 11 20:55:37 volumio3beta-hb-xlr-02 volumio[674]: error: Could not read plugin package at /data/plugins/music_service/hi_res_audio: Error: /data/plugins/music_service/hi_res_audio/package.json: ENOENT: no such file or directory, open '/data/plugins/music_service/hi_res_audio/package.json' Nov 11 20:55:37 volumio3beta-hb-xlr-02 volumio[674]: error: Could not read plugin package at /volumio/app/plugins/music_service/tidal: Error: /volumio/app/plugins/music_service/tidal/package.json: ENOENT: no such file or directory, open '/volumio/app/plugins/music_service/tidal/package.json' Nov 11 20:55:37 volumio3beta-hb-xlr-02 volumio[674]: error: Could not read plugin package at /data/plugins/music_service/tidal: Error: /data/plugins/music_service/tidal/package.json: ENOENT: no such file or directory, open '/data/plugins/music_service/tidal/package.json' Nov 11 20:55:37 volumio3beta-hb-xlr-02 volumio[674]: error: Could not read plugin package at /volumio/app/plugins/music_service/qobuz: Error: /volumio/app/plugins/music_service/qobuz/package.json: ENOENT: no such file or directory, open '/volumio/app/plugins/music_service/qobuz/package.json' Nov 11 20:55:37 volumio3beta-hb-xlr-02 volumio[674]: error: Could not read plugin package at /data/plugins/music_service/qobuz: Error: /data/plugins/music_service/qobuz/package.json: ENOENT: no such file or directory, open '/data/plugins/music_service/qobuz/package.json' Nov 11 20:55:37 volumio3beta-hb-xlr-02 volumio[674]: error: Could not read plugin package at /volumio/app/plugins/music_service/tidalconnect: Error: /volumio/app/plugins/music_service/tidalconnect/package.json: ENOENT: no such file or directory, open '/volumio/app/plugins/music_service/tidalconnect/package.json' Nov 11 20:55:37 volumio3beta-hb-xlr-02 volumio[674]: error: Could not read plugin package at /data/plugins/music_service/tidalconnect: Error: /data/plugins/music_service/tidalconnect/package.json: ENOENT: no such file or directory, open '/data/plugins/music_service/tidalconnect/package.json' Nov 11 20:55:37 volumio3beta-hb-xlr-02 volumio[674]: error: Could not read plugin package at /data/plugins/audio_interface/outputs: Error: /data/plugins/audio_interface/outputs/package.json: ENOENT: no such file or directory, open '/data/plugins/audio_interface/outputs/package.json' Nov 11 20:55:37 volumio3beta-hb-xlr-02 volumio[674]: error: Could not read plugin package at /myvolumio/plugins/audio_interface/outputs: Error: /myvolumio/plugins/audio_interface/outputs/package.json: ENOENT: no such file or directory, open '/myvolumio/plugins/audio_interface/outputs/package.json' Nov 11 20:55:37 volumio3beta-hb-xlr-02 volumio[674]: error: Could not read plugin package at /data/plugins/audio_interface/upnp: Error: /data/plugins/audio_interface/upnp/package.json: ENOENT: no such file or directory, open '/data/plugins/audio_interface/upnp/package.json' Nov 11 20:55:37 volumio3beta-hb-xlr-02 volumio[674]: error: Could not read plugin package at /myvolumio/plugins/audio_interface/upnp: Error: /myvolumio/plugins/audio_interface/upnp/package.json: ENOENT: no such file or directory, open '/myvolumio/plugins/audio_interface/upnp/package.json' Nov 11 20:55:37 volumio3beta-hb-xlr-02 volumio[674]: error: Could not read plugin package at /data/plugins/audio_interface/alsa_controller: Error: /data/plugins/audio_interface/alsa_controller/package.json: ENOENT: no such file or directory, open '/data/plugins/audio_interface/alsa_controller/package.json' Nov 11 20:55:37 volumio3beta-hb-xlr-02 volumio[674]: error: Could not read plugin package at /myvolumio/plugins/audio_interface/alsa_controller: Error: /myvolumio/plugins/audio_interface/alsa_controller/package.json: ENOENT: no such file or directory, open '/myvolumio/plugins/audio_interface/alsa_controller/package.json' Nov 11 20:55:37 volumio3beta-hb-xlr-02 volumio[674]: error: Could not read plugin package at /volumio/app/plugins/audio_interface/bluetooth: Error: /volumio/app/plugins/audio_interface/bluetooth/package.json: ENOENT: no such file or directory, open '/volumio/app/plugins/audio_interface/bluetooth/package.json' Nov 11 20:55:37 volumio3beta-hb-xlr-02 volumio[674]: error: Could not read plugin package at /data/plugins/audio_interface/bluetooth: Error: /data/plugins/audio_interface/bluetooth/package.json: ENOENT: no such file or directory, open '/data/plugins/audio_interface/bluetooth/package.json' Nov 11 20:55:37 volumio3beta-hb-xlr-02 volumio[674]: error: Could not read plugin package at /volumio/app/plugins/audio_interface/multiroom: Error: /volumio/app/plugins/audio_interface/multiroom/package.json: ENOENT: no such file or directory, open '/volumio/app/plugins/audio_interface/multiroom/package.json' Nov 11 20:55:37 volumio3beta-hb-xlr-02 volumio[674]: error: Could not read plugin package at /data/plugins/audio_interface/multiroom: Error: /data/plugins/audio_interface/multiroom/package.json: ENOENT: no such file or directory, open '/data/plugins/audio_interface/multiroom/package.json' Nov 11 20:55:37 volumio3beta-hb-xlr-02 volumio[674]: error: Could not read plugin package at /data/plugins/miscellanea/alarm-clock: Error: /data/plugins/miscellanea/alarm-clock/package.json: ENOENT: no such file or directory, open '/data/plugins/miscellanea/alarm-clock/package.json' Nov 11 20:55:37 volumio3beta-hb-xlr-02 volumio[674]: error: Could not read plugin package at /myvolumio/plugins/miscellanea/alarm-clock: Error: /myvolumio/plugins/miscellanea/alarm-clock/package.json: ENOENT: no such file or directory, open '/myvolumio/plugins/miscellanea/alarm-clock/package.json' Nov 11 20:55:37 volumio3beta-hb-xlr-02 volumio[674]: error: Could not read plugin package at /data/plugins/miscellanea/albumart: Error: /data/plugins/miscellanea/albumart/package.json: ENOENT: no such file or directory, open '/data/plugins/miscellanea/albumart/package.json' Nov 11 20:55:37 volumio3beta-hb-xlr-02 volumio[674]: error: Could not read plugin package at /myvolumio/plugins/miscellanea/albumart: Error: /myvolumio/plugins/miscellanea/albumart/package.json: ENOENT: no such file or directory, open '/myvolumio/plugins/miscellanea/albumart/package.json' Nov 11 20:55:37 volumio3beta-hb-xlr-02 volumio[674]: error: Could not read plugin package at /data/plugins/miscellanea/appearance: Error: /data/plugins/miscellanea/appearance/package.json: ENOENT: no such file or directory, open '/data/plugins/miscellanea/appearance/package.json' Nov 11 20:55:37 volumio3beta-hb-xlr-02 volumio[674]: error: Could not read plugin package at /myvolumio/plugins/miscellanea/appearance: Error: /myvolumio/plugins/miscellanea/appearance/package.json: ENOENT: no such file or directory, open '/myvolumio/plugins/miscellanea/appearance/package.json' Nov 11 20:55:37 volumio3beta-hb-xlr-02 volumio[674]: error: Could not read plugin package at /data/plugins/miscellanea/wizard: Error: /data/plugins/miscellanea/wizard/package.json: ENOENT: no such file or directory, open '/data/plugins/miscellanea/wizard/package.json' Nov 11 20:55:37 volumio3beta-hb-xlr-02 volumio[674]: error: Could not read plugin package at /myvolumio/plugins/miscellanea/wizard: Error: /myvolumio/plugins/miscellanea/wizard/package.json: ENOENT: no such file or directory, open '/myvolumio/plugins/miscellanea/wizard/package.json' Nov 11 20:55:37 volumio3beta-hb-xlr-02 volumio[674]: error: Could not read plugin package at /data/plugins/miscellanea/my_music: Error: /data/plugins/miscellanea/my_music/package.json: ENOENT: no such file or directory, open '/data/plugins/miscellanea/my_music/package.json' Nov 11 20:55:37 volumio3beta-hb-xlr-02 volumio[674]: error: Could not read plugin package at /myvolumio/plugins/miscellanea/my_music: Error: /myvolumio/plugins/miscellanea/my_music/package.json: ENOENT: no such file or directory, open '/myvolumio/plugins/miscellanea/my_music/package.json' Nov 11 20:55:37 volumio3beta-hb-xlr-02 volumio[674]: error: Could not read plugin package at /volumio/app/plugins/miscellanea/metavolumio: Error: /volumio/app/plugins/miscellanea/metavolumio/package.json: ENOENT: no such file or directory, open '/volumio/app/plugins/miscellanea/metavolumio/package.json' Nov 11 20:55:37 volumio3beta-hb-xlr-02 volumio[674]: error: Could not read plugin package at /data/plugins/miscellanea/metavolumio: Error: /data/plugins/miscellanea/metavolumio/package.json: ENOENT: no such file or directory, open '/data/plugins/miscellanea/metavolumio/package.json' Nov 11 20:55:37 volumio3beta-hb-xlr-02 volumio[674]: error: Could not read plugin package at /data/plugins/user_interface/websocket: Error: /data/plugins/user_interface/websocket/package.json: ENOENT: no such file or directory, open '/data/plugins/user_interface/websocket/package.json' Nov 11 20:55:37 volumio3beta-hb-xlr-02 volumio[674]: error: Could not read plugin package at /myvolumio/plugins/user_interface/websocket: Error: /myvolumio/plugins/user_interface/websocket/package.json: ENOENT: no such file or directory, open '/myvolumio/plugins/user_interface/websocket/package.json' Nov 11 20:55:37 volumio3beta-hb-xlr-02 volumio[674]: error: Could not read plugin package at /data/plugins/user_interface/mpdemulation: Error: /data/plugins/user_interface/mpdemulation/package.json: ENOENT: no such file or directory, open '/data/plugins/user_interface/mpdemulation/package.json' Nov 11 20:55:37 volumio3beta-hb-xlr-02 volumio[674]: error: Could not read plugin package at /myvolumio/plugins/user_interface/mpdemulation: Error: /myvolumio/plugins/user_interface/mpdemulation/package.json: ENOENT: no such file or directory, open '/myvolumio/plugins/user_interface/mpdemulation/package.json' Nov 11 20:55:37 volumio3beta-hb-xlr-02 volumio[674]: error: Could not read plugin package at /data/plugins/user_interface/rest_api: Error: /data/plugins/user_interface/rest_api/package.json: ENOENT: no such file or directory, open '/data/plugins/user_interface/rest_api/package.json' Nov 11 20:55:37 volumio3beta-hb-xlr-02 volumio[674]: error: Could not read plugin package at /myvolumio/plugins/user_interface/rest_api: Error: /myvolumio/plugins/user_interface/rest_api/package.json: ENOENT: no such file or directory, open '/myvolumio/plugins/user_interface/rest_api/package.json' Nov 11 20:55:37 volumio3beta-hb-xlr-02 volumio[674]: error: Could not read plugin package at /data/plugins/system_controller/updater_comm: Error: /data/plugins/system_controller/updater_comm/package.json: ENOENT: no such file or directory, open '/data/plugins/system_controller/updater_comm/package.json' Nov 11 20:55:37 volumio3beta-hb-xlr-02 volumio[674]: error: Could not read plugin package at /myvolumio/plugins/system_controller/updater_comm: Error: /myvolumio/plugins/system_controller/updater_comm/package.json: ENOENT: no such file or directory, open '/myvolumio/plugins/system_controller/updater_comm/package.json' Nov 11 20:55:37 volumio3beta-hb-xlr-02 volumio[674]: error: Could not read plugin package at /data/plugins/system_controller/network: Error: /data/plugins/system_controller/network/package.json: ENOENT: no such file or directory, open '/data/plugins/system_controller/network/package.json' Nov 11 20:55:37 volumio3beta-hb-xlr-02 volumio[674]: error: Could not read plugin package at /myvolumio/plugins/system_controller/network: Error: /myvolumio/plugins/system_controller/network/package.json: ENOENT: no such file or directory, open '/myvolumio/plugins/system_controller/network/package.json' Nov 11 20:55:37 volumio3beta-hb-xlr-02 volumio[674]: error: Could not read plugin package at /data/plugins/system_controller/networkfs: Error: /data/plugins/system_controller/networkfs/package.json: ENOENT: no such file or directory, open '/data/plugins/system_controller/networkfs/package.json' Nov 11 20:55:37 volumio3beta-hb-xlr-02 volumio[674]: error: Could not read plugin package at /myvolumio/plugins/system_controller/networkfs: Error: /myvolumio/plugins/system_controller/networkfs/package.json: ENOENT: no such file or directory, open '/myvolumio/plugins/system_controller/networkfs/package.json' Nov 11 20:55:37 volumio3beta-hb-xlr-02 volumio[674]: error: Could not read plugin package at /data/plugins/system_controller/services: Error: /data/plugins/system_controller/services/package.json: ENOENT: no such file or directory, open '/data/plugins/system_controller/services/package.json' Nov 11 20:55:37 volumio3beta-hb-xlr-02 volumio[674]: error: Could not read plugin package at /myvolumio/plugins/system_controller/services: Error: /myvolumio/plugins/system_controller/services/package.json: ENOENT: no such file or directory, open '/myvolumio/plugins/system_controller/services/package.json' Nov 11 20:55:37 volumio3beta-hb-xlr-02 volumio[674]: error: Could not read plugin package at /data/plugins/system_controller/system: Error: /data/plugins/system_controller/system/package.json: ENOENT: no such file or directory, open '/data/plugins/system_controller/system/package.json' Nov 11 20:55:37 volumio3beta-hb-xlr-02 volumio[674]: error: Could not read plugin package at /myvolumio/plugins/system_controller/system: Error: /myvolumio/plugins/system_controller/system/package.json: ENOENT: no such file or directory, open '/myvolumio/plugins/system_controller/system/package.json' Nov 11 20:55:37 volumio3beta-hb-xlr-02 volumio[674]: error: Could not read plugin package at /data/plugins/system_controller/i2s_dacs: Error: /data/plugins/system_controller/i2s_dacs/package.json: ENOENT: no such file or directory, open '/data/plugins/system_controller/i2s_dacs/package.json' Nov 11 20:55:37 volumio3beta-hb-xlr-02 volumio[674]: error: Could not read plugin package at /myvolumio/plugins/system_controller/i2s_dacs: Error: /myvolumio/plugins/system_controller/i2s_dacs/package.json: ENOENT: no such file or directory, open '/myvolumio/plugins/system_controller/i2s_dacs/package.json' Nov 11 20:55:37 volumio3beta-hb-xlr-02 volumio[674]: error: Could not read plugin package at /data/plugins/system_controller/volumiodiscovery: Error: /data/plugins/system_controller/volumiodiscovery/package.json: ENOENT: no such file or directory, open '/data/plugins/system_controller/volumiodiscovery/package.json' Nov 11 20:55:37 volumio3beta-hb-xlr-02 volumio[674]: error: Could not read plugin package at /myvolumio/plugins/system_controller/volumiodiscovery: Error: /myvolumio/plugins/system_controller/volumiodiscovery/package.json: ENOENT: no such file or directory, open '/myvolumio/plugins/system_controller/volumiodiscovery/package.json' Nov 11 20:55:37 volumio3beta-hb-xlr-02 volumio[674]: error: Could not read plugin package at /data/plugins/system_controller/volumio_command_line_client: Error: /data/plugins/system_controller/volumio_command_line_client/package.json: ENOENT: no such file or directory, open '/data/plugins/system_controller/volumio_command_line_client/package.json' Nov 11 20:55:37 volumio3beta-hb-xlr-02 volumio[674]: error: Could not read plugin package at /myvolumio/plugins/system_controller/volumio_command_line_client: Error: /myvolumio/plugins/system_controller/volumio_command_line_client/package.json: ENOENT: no such file or directory, open '/myvolumio/plugins/system_controller/volumio_command_line_client/package.json' Nov 11 20:55:37 volumio3beta-hb-xlr-02 volumio[674]: error: Could not read plugin package at /volumio/app/plugins/system_controller/my_volumio: Error: /volumio/app/plugins/system_controller/my_volumio/package.json: ENOENT: no such file or directory, open '/volumio/app/plugins/system_controller/my_volumio/package.json' Nov 11 20:55:37 volumio3beta-hb-xlr-02 volumio[674]: error: Could not read plugin package at /data/plugins/system_controller/my_volumio: Error: /data/plugins/system_controller/my_volumio/package.json: ENOENT: no such file or directory, open '/data/plugins/system_controller/my_volumio/package.json' Nov 11 20:55:37 volumio3beta-hb-xlr-02 volumio[674]: info: Plugin folders cleanup Nov 11 20:55:37 volumio3beta-hb-xlr-02 volumio[674]: info: Scanning into folder /volumio/app/plugins/ Nov 11 20:55:37 volumio3beta-hb-xlr-02 volumio[674]: info: Scanning category audio_interface Nov 11 20:55:37 volumio3beta-hb-xlr-02 volumio[674]: info: Scanning category miscellanea Nov 11 20:55:37 volumio3beta-hb-xlr-02 volumio[674]: info: Scanning category music_service Nov 11 20:55:37 volumio3beta-hb-xlr-02 volumio[674]: info: Scanning category plugins.json Nov 11 20:55:37 volumio3beta-hb-xlr-02 volumio[674]: info: Scanning category system_controller Nov 11 20:55:37 volumio3beta-hb-xlr-02 volumio[674]: info: Scanning category user_interface Nov 11 20:55:37 volumio3beta-hb-xlr-02 volumio[674]: info: Scanning into folder /data/plugins/ Nov 11 20:55:37 volumio3beta-hb-xlr-02 volumio[674]: info: Plugin folders cleanup completed Nov 11 20:55:37 volumio3beta-hb-xlr-02 volumio[674]: info: ------------------------------------------- Nov 11 20:55:37 volumio3beta-hb-xlr-02 volumio[674]: info: ----- Core plugins startup ---- Nov 11 20:55:37 volumio3beta-hb-xlr-02 volumio[674]: info: ------------------------------------------- Nov 11 20:55:37 volumio3beta-hb-xlr-02 volumio[674]: info: Loading plugins from folder /volumio/app/plugins/ Nov 11 20:55:37 volumio3beta-hb-xlr-02 volumio[674]: info: Adding plugin upnp to MyMusic Plugins Nov 11 20:55:37 volumio3beta-hb-xlr-02 volumio[674]: info: Adding plugin airplay_emulation to MyMusic Plugins Nov 11 20:55:37 volumio3beta-hb-xlr-02 volumio[674]: info: Adding plugin upnp_browser to MyMusic Plugins Nov 11 20:55:37 volumio3beta-hb-xlr-02 volumio[674]: info: Loading plugins from folder /data/plugins/ Nov 11 20:55:37 volumio3beta-hb-xlr-02 volumio[674]: info: Loading plugin "system"... Nov 11 20:55:37 volumio3beta-hb-xlr-02 volumio[674]: info: Loading plugin "appearance"... Nov 11 20:55:40 volumio3beta-hb-xlr-02 volumio[674]: info: Loading plugin "network"... Nov 11 20:55:40 volumio3beta-hb-xlr-02 volumio[674]: info: Refreshing Cached IP Addresses Nov 11 20:55:40 volumio3beta-hb-xlr-02 volumio[674]: info: Loading plugin "services"... Nov 11 20:55:40 volumio3beta-hb-xlr-02 sudo[714]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Nov 11 20:55:40 volumio3beta-hb-xlr-02 sudo[714]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 11 20:55:40 volumio3beta-hb-xlr-02 sudo[714]: pam_unix(sudo:session): session closed for user root Nov 11 20:55:40 volumio3beta-hb-xlr-02 volumio[674]: info: Loading plugin "alsa_controller"... Nov 11 20:55:40 volumio3beta-hb-xlr-02 sudo[716]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Nov 11 20:55:40 volumio3beta-hb-xlr-02 sudo[716]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 11 20:55:40 volumio3beta-hb-xlr-02 sudo[716]: pam_unix(sudo:session): session closed for user root Nov 11 20:55:40 volumio3beta-hb-xlr-02 volumio[674]: info: Loading plugin "wizard"... Nov 11 20:55:40 volumio3beta-hb-xlr-02 volumio[674]: info: Loading plugin "volumio_command_line_client"... Nov 11 20:55:40 volumio3beta-hb-xlr-02 volumio[674]: info: Loading plugin "upnp"... Nov 11 20:55:40 volumio3beta-hb-xlr-02 volumio[674]: info: [1636664140969] Starting Upmpd Daemon Nov 11 20:55:40 volumio3beta-hb-xlr-02 volumio[674]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 11 20:55:40 volumio3beta-hb-xlr-02 volumio[674]: info: Loading plugin "my_music"... Nov 11 20:55:40 volumio3beta-hb-xlr-02 volumio[674]: info: Loading plugin "mpd"... Nov 11 20:55:41 volumio3beta-hb-xlr-02 volumio[674]: info: Creating MPD Configuration file Nov 11 20:55:41 volumio3beta-hb-xlr-02 volumio[674]: info: Loading plugin "upnp_browser"... Nov 11 20:55:41 volumio3beta-hb-xlr-02 sudo[724]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 11 20:55:41 volumio3beta-hb-xlr-02 sudo[724]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 11 20:55:41 volumio3beta-hb-xlr-02 sudo[726]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 11 20:55:41 volumio3beta-hb-xlr-02 sudo[724]: pam_unix(sudo:session): session closed for user root Nov 11 20:55:41 volumio3beta-hb-xlr-02 sudo[726]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 11 20:55:41 volumio3beta-hb-xlr-02 systemd[1]: Stopping Music Player Daemon... Nov 11 20:55:42 volumio3beta-hb-xlr-02 systemd[1]: mpd.service: Succeeded. Nov 11 20:55:42 volumio3beta-hb-xlr-02 systemd[1]: Stopped Music Player Daemon. Nov 11 20:55:42 volumio3beta-hb-xlr-02 systemd[1]: Starting Music Player Daemon... Nov 11 20:55:42 volumio3beta-hb-xlr-02 volumio[674]: info: Loading plugin "networkfs"... Nov 11 20:55:43 volumio3beta-hb-xlr-02 volumio[674]: info: Starting Udev Watcher for removable devices Nov 11 20:55:43 volumio3beta-hb-xlr-02 sudo[734]: 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.178.11/music /mnt/NAS/Musik Nov 11 20:55:43 volumio3beta-hb-xlr-02 sudo[734]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 11 20:55:43 volumio3beta-hb-xlr-02 kernel: FS-Cache: Netfs 'cifs' registered for caching Nov 11 20:55:43 volumio3beta-hb-xlr-02 kernel: Key type cifs.spnego registered Nov 11 20:55:43 volumio3beta-hb-xlr-02 kernel: Key type cifs.idmap registered Nov 11 20:55:43 volumio3beta-hb-xlr-02 kernel: CIFS: Attempting to mount //192.168.178.11/music Nov 11 20:55:43 volumio3beta-hb-xlr-02 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. Nov 11 20:55:44 volumio3beta-hb-xlr-02 sudo[734]: pam_unix(sudo:session): session closed for user root Nov 11 20:55:44 volumio3beta-hb-xlr-02 volumio[674]: info: Ignoring mount for partition: boot Nov 11 20:55:44 volumio3beta-hb-xlr-02 volumio[674]: info: Ignoring mount for partition: volumio Nov 11 20:55:44 volumio3beta-hb-xlr-02 volumio[674]: info: Ignoring mount for partition: volumio_data Nov 11 20:55:44 volumio3beta-hb-xlr-02 volumio[674]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 11 20:55:44 volumio3beta-hb-xlr-02 volumio[674]: info: Loading plugin "alarm-clock"... Nov 11 20:55:44 volumio3beta-hb-xlr-02 mpd[731]: Nov 11 20:55 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Nov 11 20:55:44 volumio3beta-hb-xlr-02 volumio[674]: info: Loading plugin "airplay_emulation"... Nov 11 20:55:45 volumio3beta-hb-xlr-02 volumio[674]: info: Starting Shairport Sync Nov 11 20:55:45 volumio3beta-hb-xlr-02 volumio[674]: info: Loading plugin "last_100"... Nov 11 20:55:45 volumio3beta-hb-xlr-02 volumio[674]: info: Loading plugin "webradio"... Nov 11 20:55:45 volumio3beta-hb-xlr-02 volumio[674]: info: Loading plugin "i2s_dacs"... Nov 11 20:55:45 volumio3beta-hb-xlr-02 volumio[674]: info: Loading plugin "volumiodiscovery"... Nov 11 20:55:45 volumio3beta-hb-xlr-02 mpd[731]: Nov 11 20:55 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Nov 11 20:55:45 volumio3beta-hb-xlr-02 mpd[731]: Nov 11 20:55 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Nov 11 20:55:45 volumio3beta-hb-xlr-02 systemd[1]: Started Music Player Daemon. Nov 11 20:55:45 volumio3beta-hb-xlr-02 volumio[674]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Nov 11 20:55:45 volumio3beta-hb-xlr-02 volumio[674]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 11 20:55:45 volumio3beta-hb-xlr-02 volumio[674]: *** WARNING *** For more information see Nov 11 20:55:45 volumio3beta-hb-xlr-02 volumio[674]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Nov 11 20:55:45 volumio3beta-hb-xlr-02 volumio[674]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 11 20:55:45 volumio3beta-hb-xlr-02 volumio[674]: *** WARNING *** For more information see Nov 11 20:55:45 volumio3beta-hb-xlr-02 node[674]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Nov 11 20:55:45 volumio3beta-hb-xlr-02 node[674]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 11 20:55:45 volumio3beta-hb-xlr-02 node[674]: *** WARNING *** For more information see Nov 11 20:55:45 volumio3beta-hb-xlr-02 node[674]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Nov 11 20:55:45 volumio3beta-hb-xlr-02 node[674]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 11 20:55:45 volumio3beta-hb-xlr-02 node[674]: *** WARNING *** For more information see Nov 11 20:55:45 volumio3beta-hb-xlr-02 sudo[726]: pam_unix(sudo:session): session closed for user root Nov 11 20:55:45 volumio3beta-hb-xlr-02 volumio[674]: info: Applying required configuration parameters for plugin volumiodiscovery Nov 11 20:55:45 volumio3beta-hb-xlr-02 volumio[674]: info: Discovery: Started advertising with name: Volumio3Beta-hb-xlr-02 Nov 11 20:55:45 volumio3beta-hb-xlr-02 volumio[674]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 11 20:55:45 volumio3beta-hb-xlr-02 volumio[674]: info: Loading plugin "outputs"... Nov 11 20:55:45 volumio3beta-hb-xlr-02 volumio[674]: info: Loading plugin "albumart"... Nov 11 20:55:45 volumio3beta-hb-xlr-02 volumio[674]: info: Plugin example_plugin is not enabled Nov 11 20:55:45 volumio3beta-hb-xlr-02 volumio[674]: info: Loading plugin "inputs"... Nov 11 20:55:45 volumio3beta-hb-xlr-02 volumio[674]: info: Loading plugin "updater_comm"... Nov 11 20:55:46 volumio3beta-hb-xlr-02 volumio[674]: info: Plugin mpdemulation is not enabled Nov 11 20:55:46 volumio3beta-hb-xlr-02 volumio[674]: info: Loading plugin "rest_api"... Nov 11 20:55:46 volumio3beta-hb-xlr-02 volumio[674]: info: Loading plugin "websocket"... Nov 11 20:55:46 volumio3beta-hb-xlr-02 volumio[674]: info: Loading i18n strings for locale de Nov 11 20:55:46 volumio3beta-hb-xlr-02 volumio[674]: Updating browse sources language Nov 11 20:55:46 volumio3beta-hb-xlr-02 volumio[674]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 11 20:55:46 volumio3beta-hb-xlr-02 volumio[674]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 11 20:55:46 volumio3beta-hb-xlr-02 volumio[674]: info: CoreCommandRouter::initPlayerControls Nov 11 20:55:46 volumio3beta-hb-xlr-02 volumio[674]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 11 20:55:46 volumio3beta-hb-xlr-02 volumio[674]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 11 20:55:46 volumio3beta-hb-xlr-02 volumio[674]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 11 20:55:46 volumio3beta-hb-xlr-02 volumio[674]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 11 20:55:46 volumio3beta-hb-xlr-02 volumio[674]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 11 20:55:46 volumio3beta-hb-xlr-02 volumio[674]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 11 20:55:46 volumio3beta-hb-xlr-02 volumio[674]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 11 20:55:46 volumio3beta-hb-xlr-02 volumio[674]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 11 20:55:46 volumio3beta-hb-xlr-02 volumio[674]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 11 20:55:46 volumio3beta-hb-xlr-02 volumio[674]: Express server listening on port 3000 Nov 11 20:55:46 volumio3beta-hb-xlr-02 volumio[674]: [Metrics] WebUI: 12s 998.05ms Nov 11 20:55:46 volumio3beta-hb-xlr-02 volumio[674]: info: CoreStateMachine::resetVolumioState Nov 11 20:55:46 volumio3beta-hb-xlr-02 volumio[674]: info: CoreStateMachine::getcurrentVolume Nov 11 20:55:46 volumio3beta-hb-xlr-02 volumio[674]: info: CoreCommandRouter::volumioRetrievevolume Nov 11 20:55:46 volumio3beta-hb-xlr-02 volumio[674]: Forking 3 albumart workers Nov 11 20:55:46 volumio3beta-hb-xlr-02 volumio[674]: info: MPD Permissions set Nov 11 20:55:46 volumio3beta-hb-xlr-02 volumio[674]: verbose: New Socket.io Connection from 192.168.178.172 from 192.168.178.182 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/95.0.4638.69 Safari/537.36 Total Clients: 1 Nov 11 20:55:46 volumio3beta-hb-xlr-02 volumio[674]: verbose: New Socket.io Connection from 192.168.178.172 from 192.168.178.182 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/95.0.4638.69 Safari/537.36 Total Clients: 2 Nov 11 20:55:46 volumio3beta-hb-xlr-02 volumio[674]: verbose: New Socket.io Connection from 192.168.178.172 from 192.168.178.182 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/95.0.4638.69 Safari/537.36 Total Clients: 3 Nov 11 20:55:46 volumio3beta-hb-xlr-02 volumio[674]: verbose: New Socket.io Connection from 192.168.178.172 from 192.168.178.182 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/95.0.4638.69 Safari/537.36 Total Clients: 4 Nov 11 20:55:46 volumio3beta-hb-xlr-02 volumio[674]: verbose: New Socket.io Connection from 192.168.178.172 from 192.168.178.182 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/95.0.4638.69 Safari/537.36 Total Clients: 5 Nov 11 20:55:46 volumio3beta-hb-xlr-02 volumio[674]: verbose: New Socket.io Connection from 192.168.178.172:3000 from 192.168.178.24 UA: node-XMLHttpRequest Total Clients: 6 Nov 11 20:55:47 volumio3beta-hb-xlr-02 volumio[674]: verbose: New Socket.io Connection from 192.168.178.172 from 192.168.178.182 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/95.0.4638.69 Safari/537.36 Total Clients: 7 Nov 11 20:55:47 volumio3beta-hb-xlr-02 volumio[674]: verbose: New Socket.io Connection from 192.168.178.172 from 192.168.178.182 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/95.0.4638.69 Safari/537.36 Total Clients: 8 Nov 11 20:55:47 volumio3beta-hb-xlr-02 volumio[674]: info: MPD running with PID731 Nov 11 20:55:47 volumio3beta-hb-xlr-02 volumio[674]: ,establishing connection Nov 11 20:55:47 volumio3beta-hb-xlr-02 volumio[674]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 11 20:55:47 volumio3beta-hb-xlr-02 volumio[674]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 11 20:55:47 volumio3beta-hb-xlr-02 volumio[674]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 11 20:55:47 volumio3beta-hb-xlr-02 volumio[674]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 11 20:55:47 volumio3beta-hb-xlr-02 volumio[674]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 11 20:55:47 volumio3beta-hb-xlr-02 volumio[674]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 11 20:55:47 volumio3beta-hb-xlr-02 volumio[674]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 11 20:55:47 volumio3beta-hb-xlr-02 volumio[674]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 11 20:55:47 volumio3beta-hb-xlr-02 volumio[674]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 11 20:55:47 volumio3beta-hb-xlr-02 volumio[674]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 11 20:55:47 volumio3beta-hb-xlr-02 volumio[674]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 11 20:55:47 volumio3beta-hb-xlr-02 volumio[674]: info: Reloading queue from file Nov 11 20:55:47 volumio3beta-hb-xlr-02 volumio[674]: verbose: New Socket.io Connection from 192.168.178.172 from 192.168.178.182 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/95.0.4638.69 Safari/537.36 Total Clients: 9 Nov 11 20:55:47 volumio3beta-hb-xlr-02 volumio[674]: error: updateQueue error: null Nov 11 20:55:47 volumio3beta-hb-xlr-02 volumio[674]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Nov 11 20:55:47 volumio3beta-hb-xlr-02 volumio[674]: info: VolumeController:: Volume=10 Mute =false Nov 11 20:55:47 volumio3beta-hb-xlr-02 volumio[674]: info: CoreStateMachine::pushState Nov 11 20:55:47 volumio3beta-hb-xlr-02 volumio[674]: info: CorePlayQueue::getTrack 0 Nov 11 20:55:47 volumio3beta-hb-xlr-02 volumio[674]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 11 20:55:47 volumio3beta-hb-xlr-02 volumio[674]: info: CoreCommandRouter::volumioPushState Nov 11 20:55:47 volumio3beta-hb-xlr-02 volumio[674]: info: CoreStateMachine::updateTrackBlock Nov 11 20:55:47 volumio3beta-hb-xlr-02 volumio[674]: info: CorePlayQueue::getTrackBlock Nov 11 20:55:47 volumio3beta-hb-xlr-02 volumio[674]: info: CoreCommandRouter::volumioRetrievevolume Nov 11 20:55:47 volumio3beta-hb-xlr-02 volumio[674]: info: CoreStateMachine::setRepeat null single undefined Nov 11 20:55:47 volumio3beta-hb-xlr-02 volumio[674]: info: CoreStateMachine::pushState Nov 11 20:55:47 volumio3beta-hb-xlr-02 volumio[674]: info: CorePlayQueue::getTrack 0 Nov 11 20:55:47 volumio3beta-hb-xlr-02 volumio[674]: info: CoreCommandRouter::volumioPushState Nov 11 20:55:47 volumio3beta-hb-xlr-02 volumio[674]: info: CoreStateMachine::setRandom null Nov 11 20:55:47 volumio3beta-hb-xlr-02 volumio[674]: info: CoreStateMachine::pushState Nov 11 20:55:47 volumio3beta-hb-xlr-02 volumio[674]: info: CorePlayQueue::getTrack 0 Nov 11 20:55:47 volumio3beta-hb-xlr-02 volumio[674]: info: CoreCommandRouter::volumioPushState Nov 11 20:55:47 volumio3beta-hb-xlr-02 volumio[674]: info: Setting Device type: Raspberry PI Nov 11 20:55:48 volumio3beta-hb-xlr-02 volumio[674]: info: Completed loading Core Plugins Nov 11 20:55:48 volumio3beta-hb-xlr-02 volumio[674]: info: Preparing to generate the ALSA configuration file Nov 11 20:55:48 volumio3beta-hb-xlr-02 volumio[674]: Starting albumart workers Nov 11 20:55:48 volumio3beta-hb-xlr-02 volumio[674]: Starting albumart workers Nov 11 20:55:48 volumio3beta-hb-xlr-02 volumio[674]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , initSocket Nov 11 20:55:48 volumio3beta-hb-xlr-02 volumio[674]: error: updateQueue error: null Nov 11 20:55:48 volumio3beta-hb-xlr-02 volumio[674]: info: CoreCommandRouter::volumioGetState Nov 11 20:55:48 volumio3beta-hb-xlr-02 volumio[674]: info: CorePlayQueue::getTrack 0 Nov 11 20:55:48 volumio3beta-hb-xlr-02 volumio[674]: info: Discovery: adding 96e83335-2be5-4677-99e6-60de2d8f9d56 Nov 11 20:55:48 volumio3beta-hb-xlr-02 volumio[674]: info: mDNS: Found device Volumio3Beta-hb-xlr-02 Nov 11 20:55:48 volumio3beta-hb-xlr-02 volumio[674]: info: CoreCommandRouter::volumioGetState Nov 11 20:55:48 volumio3beta-hb-xlr-02 volumio[674]: info: CorePlayQueue::getTrack 0 Nov 11 20:55:48 volumio3beta-hb-xlr-02 volumio[674]: Starting albumart workers Nov 11 20:55:48 volumio3beta-hb-xlr-02 volumio[674]: info: CoreCommandRouter::volumioGetState Nov 11 20:55:48 volumio3beta-hb-xlr-02 volumio[674]: info: CorePlayQueue::getTrack 0 Nov 11 20:55:48 volumio3beta-hb-xlr-02 volumio[674]: info: VolumeController:: Volume=10 Mute =false Nov 11 20:55:48 volumio3beta-hb-xlr-02 volumio[674]: info: CoreStateMachine::pushState Nov 11 20:55:48 volumio3beta-hb-xlr-02 volumio[674]: info: CorePlayQueue::getTrack 0 Nov 11 20:55:48 volumio3beta-hb-xlr-02 volumio[674]: info: CoreCommandRouter::volumioPushState Nov 11 20:55:49 volumio3beta-hb-xlr-02 volumio[674]: info: Discovery: this is already registered, 96e83335-2be5-4677-99e6-60de2d8f9d56 Nov 11 20:55:49 volumio3beta-hb-xlr-02 volumio[674]: info: mDNS: Found device Volumio3Beta-hb-xlr-02 Nov 11 20:55:49 volumio3beta-hb-xlr-02 volumio[674]: info: CoreCommandRouter::volumioGetState Nov 11 20:55:49 volumio3beta-hb-xlr-02 volumio[674]: info: CorePlayQueue::getTrack 0 Nov 11 20:55:49 volumio3beta-hb-xlr-02 volumio[674]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Nov 11 20:55:49 volumio3beta-hb-xlr-02 volumio[674]: info: CoreCommandRouter::volumioGetVisibleSources Nov 11 20:55:49 volumio3beta-hb-xlr-02 volumio[674]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 11 20:55:49 volumio3beta-hb-xlr-02 volumio[674]: info: Listing playlists Nov 11 20:55:49 volumio3beta-hb-xlr-02 volumio[674]: info: Discovery: adding f7e8c432-202b-4163-9c31-ee97b3701219 Nov 11 20:55:49 volumio3beta-hb-xlr-02 volumio[674]: info: mDNS: Found device Volumio-boss-01 Nov 11 20:55:49 volumio3beta-hb-xlr-02 systemd[1]: systemd-fsckd.service: Succeeded. Nov 11 20:55:49 volumio3beta-hb-xlr-02 volumio[674]: info: Asound.conf file written Nov 11 20:55:50 volumio3beta-hb-xlr-02 sudo[828]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Nov 11 20:55:50 volumio3beta-hb-xlr-02 sudo[828]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 11 20:55:50 volumio3beta-hb-xlr-02 sudo[828]: pam_unix(sudo:session): session closed for user root Nov 11 20:55:50 volumio3beta-hb-xlr-02 volumio[674]: info: Output device has changed, restarting MPD Nov 11 20:55:50 volumio3beta-hb-xlr-02 volumio[674]: info: Output device has changed, restarting Shairport Sync Nov 11 20:55:50 volumio3beta-hb-xlr-02 sudo[844]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 11 20:55:50 volumio3beta-hb-xlr-02 sudo[844]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 11 20:55:50 volumio3beta-hb-xlr-02 sudo[844]: pam_unix(sudo:session): session closed for user root Nov 11 20:55:50 volumio3beta-hb-xlr-02 sudo[851]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 11 20:55:50 volumio3beta-hb-xlr-02 sudo[851]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 11 20:55:50 volumio3beta-hb-xlr-02 volumio[674]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 11 20:55:50 volumio3beta-hb-xlr-02 systemd[1]: Stopping Music Player Daemon... Nov 11 20:55:50 volumio3beta-hb-xlr-02 volumio[674]: info: ___________ START PLUGINS ___________ Nov 11 20:55:50 volumio3beta-hb-xlr-02 systemd[1]: mpd.service: Succeeded. Nov 11 20:55:50 volumio3beta-hb-xlr-02 systemd[1]: Stopped Music Player Daemon. Nov 11 20:55:50 volumio3beta-hb-xlr-02 systemd[1]: Starting Music Player Daemon... Nov 11 20:55:50 volumio3beta-hb-xlr-02 volumio[674]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 11 20:55:50 volumio3beta-hb-xlr-02 volumio[674]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 11 20:55:50 volumio3beta-hb-xlr-02 volumio[674]: info: [1636664150533] CoreMusicLibrary::Adding element Media Servers Nov 11 20:55:50 volumio3beta-hb-xlr-02 volumio[674]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 11 20:55:50 volumio3beta-hb-xlr-02 volumio[674]: Cannot find translation for sourceMedia Servers Nov 11 20:55:50 volumio3beta-hb-xlr-02 volumio[674]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 11 20:55:50 volumio3beta-hb-xlr-02 volumio[674]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 11 20:55:50 volumio3beta-hb-xlr-02 volumio[674]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 11 20:55:50 volumio3beta-hb-xlr-02 volumio[674]: info: [1636664150784] CoreMusicLibrary::Adding element Last_100 Nov 11 20:55:50 volumio3beta-hb-xlr-02 volumio[674]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 11 20:55:50 volumio3beta-hb-xlr-02 volumio[674]: Cannot find translation for sourceMedia Servers Nov 11 20:55:50 volumio3beta-hb-xlr-02 volumio[674]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 11 20:55:50 volumio3beta-hb-xlr-02 volumio[674]: info: [1636664150795] CoreMusicLibrary::Adding element Webradio Nov 11 20:55:50 volumio3beta-hb-xlr-02 volumio[674]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 11 20:55:50 volumio3beta-hb-xlr-02 volumio[674]: Cannot find translation for sourceMedia Servers Nov 11 20:55:50 volumio3beta-hb-xlr-02 volumio[674]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 11 20:55:50 volumio3beta-hb-xlr-02 volumio[674]: info: Volumio Calling Home Nov 11 20:55:50 volumio3beta-hb-xlr-02 volumio-remote-updater[386]: [2021-11-11 20:55:50] [connect] Successful connection Nov 11 20:55:50 volumio3beta-hb-xlr-02 volumio[674]: info: Completed starting Core Plugins Nov 11 20:55:50 volumio3beta-hb-xlr-02 volumio[674]: info: ------------------------------------------- Nov 11 20:55:50 volumio3beta-hb-xlr-02 volumio[674]: info: ----- MyVolumio plugins startup ---- Nov 11 20:55:50 volumio3beta-hb-xlr-02 volumio[674]: info: ------------------------------------------- Nov 11 20:55:50 volumio3beta-hb-xlr-02 volumio[674]: info: [MyVolumio PluginManager] Fetching plans data.... Nov 11 20:55:51 volumio3beta-hb-xlr-02 sudo[869]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Nov 11 20:55:51 volumio3beta-hb-xlr-02 sudo[869]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 11 20:55:51 volumio3beta-hb-xlr-02 sudo[869]: pam_unix(sudo:session): session closed for user root Nov 11 20:55:51 volumio3beta-hb-xlr-02 sudo[871]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Nov 11 20:55:51 volumio3beta-hb-xlr-02 sudo[871]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 11 20:55:51 volumio3beta-hb-xlr-02 sudo[871]: pam_unix(sudo:session): session closed for user root Nov 11 20:55:51 volumio3beta-hb-xlr-02 volumio[674]: info: MPD Permissions set Nov 11 20:55:51 volumio3beta-hb-xlr-02 volumio-remote-updater[386]: [2021-11-11 20:55:51] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1636664150 101 Nov 11 20:55:51 volumio3beta-hb-xlr-02 volumio[674]: verbose: New Socket.io Connection from 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Total Clients: 10 Nov 11 20:55:51 volumio3beta-hb-xlr-02 volumio[674]: info: Volumio called home Nov 11 20:55:51 volumio3beta-hb-xlr-02 volumio[674]: verbose: New Socket.io Connection from localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 11 Nov 11 20:55:51 volumio3beta-hb-xlr-02 volumio[674]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 11 20:55:51 volumio3beta-hb-xlr-02 volumio[674]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 11 20:55:51 volumio3beta-hb-xlr-02 volumio[674]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 11 20:55:51 volumio3beta-hb-xlr-02 volumio[674]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 11 20:55:51 volumio3beta-hb-xlr-02 volumio[674]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 11 20:55:51 volumio3beta-hb-xlr-02 volumio[674]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 11 20:55:51 volumio3beta-hb-xlr-02 volumio[674]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 11 20:55:51 volumio3beta-hb-xlr-02 volumio[674]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 11 20:55:51 volumio3beta-hb-xlr-02 volumio[674]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 11 20:55:51 volumio3beta-hb-xlr-02 volumio[674]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 11 20:55:52 volumio3beta-hb-xlr-02 volumio[674]: info: Starting Shairport Sync Nov 11 20:55:52 volumio3beta-hb-xlr-02 volumio[674]: info: Starting Shairport Sync Nov 11 20:55:52 volumio3beta-hb-xlr-02 volumio[674]: info: Starting Shairport Sync Nov 11 20:55:52 volumio3beta-hb-xlr-02 sudo[879]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Nov 11 20:55:52 volumio3beta-hb-xlr-02 sudo[879]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 11 20:55:52 volumio3beta-hb-xlr-02 sudo[881]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Nov 11 20:55:52 volumio3beta-hb-xlr-02 sudo[881]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 11 20:55:52 volumio3beta-hb-xlr-02 sudo[884]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Nov 11 20:55:52 volumio3beta-hb-xlr-02 sudo[884]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 11 20:55:52 volumio3beta-hb-xlr-02 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Nov 11 20:55:52 volumio3beta-hb-xlr-02 systemd[1]: shairport-sync.service: Succeeded. Nov 11 20:55:52 volumio3beta-hb-xlr-02 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Nov 11 20:55:52 volumio3beta-hb-xlr-02 sudo[887]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Nov 11 20:55:52 volumio3beta-hb-xlr-02 sudo[887]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 11 20:55:52 volumio3beta-hb-xlr-02 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Nov 11 20:55:52 volumio3beta-hb-xlr-02 sudo[881]: pam_unix(sudo:session): session closed for user root Nov 11 20:55:52 volumio3beta-hb-xlr-02 sudo[879]: pam_unix(sudo:session): session closed for user root Nov 11 20:55:52 volumio3beta-hb-xlr-02 systemd[1]: Started UPnP Renderer front-end to MPD. Nov 11 20:55:52 volumio3beta-hb-xlr-02 sudo[887]: pam_unix(sudo:session): session closed for user root Nov 11 20:55:52 volumio3beta-hb-xlr-02 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Nov 11 20:55:52 volumio3beta-hb-xlr-02 systemd[1]: shairport-sync.service: Succeeded. Nov 11 20:55:52 volumio3beta-hb-xlr-02 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Nov 11 20:55:52 volumio3beta-hb-xlr-02 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Nov 11 20:55:52 volumio3beta-hb-xlr-02 sudo[884]: pam_unix(sudo:session): session closed for user root Nov 11 20:55:52 volumio3beta-hb-xlr-02 volumio[674]: info: Shairport-Sync Started Nov 11 20:55:52 volumio3beta-hb-xlr-02 volumio[674]: Error adding Membership: Error: addMembership EINVAL Nov 11 20:55:52 volumio3beta-hb-xlr-02 volumio[674]: info: Shairport-Sync Started Nov 11 20:55:52 volumio3beta-hb-xlr-02 volumio[674]: info: Shairport-Sync Started Nov 11 20:55:52 volumio3beta-hb-xlr-02 volumio[674]: info: Upmpdcli Daemon Started Nov 11 20:55:52 volumio3beta-hb-xlr-02 volumio[674]: info: CoreCommandRouter::volumioGetState Nov 11 20:55:52 volumio3beta-hb-xlr-02 volumio[674]: info: CorePlayQueue::getTrack 0 Nov 11 20:55:53 volumio3beta-hb-xlr-02 mpd[867]: Nov 11 20:55 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Nov 11 20:55:53 volumio3beta-hb-xlr-02 mpd[867]: Nov 11 20:55 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Nov 11 20:55:53 volumio3beta-hb-xlr-02 mpd[867]: Nov 11 20:55 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Nov 11 20:55:53 volumio3beta-hb-xlr-02 systemd[1]: Started Music Player Daemon. Nov 11 20:55:53 volumio3beta-hb-xlr-02 sudo[851]: pam_unix(sudo:session): session closed for user root Nov 11 20:55:53 volumio3beta-hb-xlr-02 volumio[674]: error: updateQueue error: null Nov 11 20:55:53 volumio3beta-hb-xlr-02 volumio[893]: Generating RSA private key, 4096 bit long modulus (2 primes) Nov 11 20:55:59 volumio3beta-hb-xlr-02 volumio[674]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Nov 11 20:55:59 volumio3beta-hb-xlr-02 volumio[674]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Nov 11 20:55:59 volumio3beta-hb-xlr-02 volumio[674]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Nov 11 20:55:59 volumio3beta-hb-xlr-02 volumio[674]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Nov 11 20:55:59 volumio3beta-hb-xlr-02 volumio[674]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Nov 11 20:55:59 volumio3beta-hb-xlr-02 volumio[674]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Nov 11 20:55:59 volumio3beta-hb-xlr-02 volumio[674]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Nov 11 20:55:59 volumio3beta-hb-xlr-02 volumio[674]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Nov 11 20:55:59 volumio3beta-hb-xlr-02 volumio[674]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Nov 11 20:55:59 volumio3beta-hb-xlr-02 volumio[674]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Nov 11 20:55:59 volumio3beta-hb-xlr-02 volumio[674]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Nov 11 20:55:59 volumio3beta-hb-xlr-02 volumio[674]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Nov 11 20:55:59 volumio3beta-hb-xlr-02 volumio[674]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Nov 11 20:55:59 volumio3beta-hb-xlr-02 volumio[674]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Nov 11 20:55:59 volumio3beta-hb-xlr-02 volumio[674]: info: Adding plugin bluetooth to MyMusic Plugins Nov 11 20:55:59 volumio3beta-hb-xlr-02 volumio[674]: info: Adding plugin cd_controller to MyMusic Plugins Nov 11 20:55:59 volumio3beta-hb-xlr-02 volumio[674]: info: Adding plugin smart_inputs to MyMusic Plugins Nov 11 20:55:59 volumio3beta-hb-xlr-02 volumio[674]: info: Adding plugin tidalconnect to MyMusic Plugins Nov 11 20:55:59 volumio3beta-hb-xlr-02 volumio[674]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Nov 11 20:56:01 volumio3beta-hb-xlr-02 volumio[674]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Nov 11 20:56:01 volumio3beta-hb-xlr-02 volumio[674]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Nov 11 20:56:01 volumio3beta-hb-xlr-02 volumio[674]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 11 20:56:01 volumio3beta-hb-xlr-02 volumio[674]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 11 20:56:01 volumio3beta-hb-xlr-02 volumio[674]: info: Starting MyVolumio Remote Streaming Endpoints Nov 11 20:56:02 volumio3beta-hb-xlr-02 volumio[674]: info: MyVolumio login type: Token Nov 11 20:56:02 volumio3beta-hb-xlr-02 volumio[674]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Nov 11 20:56:02 volumio3beta-hb-xlr-02 volumio[674]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Nov 11 20:56:02 volumio3beta-hb-xlr-02 volumio[674]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Nov 11 20:56:02 volumio3beta-hb-xlr-02 volumio[674]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Nov 11 20:56:02 volumio3beta-hb-xlr-02 volumio[674]: info: Streaming services startup Nov 11 20:56:02 volumio3beta-hb-xlr-02 volumio[674]: info: Starting Streaming Daemon Nov 11 20:56:02 volumio3beta-hb-xlr-02 sudo[915]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Nov 11 20:56:02 volumio3beta-hb-xlr-02 sudo[915]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 11 20:56:02 volumio3beta-hb-xlr-02 systemd[1]: Stopping Volumio Streaming Daemon... Nov 11 20:56:02 volumio3beta-hb-xlr-02 systemd[1]: volumio-streaming-daemon.service: Main process exited, code=killed, status=15/TERM Nov 11 20:56:02 volumio3beta-hb-xlr-02 systemd[1]: volumio-streaming-daemon.service: Succeeded. Nov 11 20:56:02 volumio3beta-hb-xlr-02 systemd[1]: Stopped Volumio Streaming Daemon. Nov 11 20:56:02 volumio3beta-hb-xlr-02 systemd[1]: Started Volumio Streaming Daemon. Nov 11 20:56:02 volumio3beta-hb-xlr-02 sudo[915]: pam_unix(sudo:session): session closed for user root Nov 11 20:56:02 volumio3beta-hb-xlr-02 volumio-streaming-daemon[921]: ############################ Nov 11 20:56:02 volumio3beta-hb-xlr-02 volumio-streaming-daemon[921]: # Volumio Streaming Daemon # Nov 11 20:56:02 volumio3beta-hb-xlr-02 volumio-streaming-daemon[921]: # Running on port 7777 # Nov 11 20:56:02 volumio3beta-hb-xlr-02 volumio-streaming-daemon[921]: ############################ Nov 11 20:56:02 volumio3beta-hb-xlr-02 volumio-streaming-daemon[921]: Environment: production Nov 11 20:56:03 volumio3beta-hb-xlr-02 volumio[674]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Nov 11 20:56:03 volumio3beta-hb-xlr-02 volumio[674]: info: MYVOLUMIO: Adding device Nov 11 20:56:03 volumio3beta-hb-xlr-02 volumio[674]: info: MYVOLUMIO: Evaluating Server Nov 11 20:56:04 volumio3beta-hb-xlr-02 volumio[674]: info: [MyVolumio PluginManager] Subscribed plan changed to superstar Nov 11 20:56:04 volumio3beta-hb-xlr-02 volumio[674]: info: Adding audio output: Nov 11 20:56:04 volumio3beta-hb-xlr-02 volumio[674]: info: MYVOLUMIO: Adding device Nov 11 20:56:04 volumio3beta-hb-xlr-02 volumio[674]: info: MYVOLUMIO: Evaluating Server Nov 11 20:56:04 volumio3beta-hb-xlr-02 volumio[674]: info: Remote config written successfully Nov 11 20:56:04 volumio3beta-hb-xlr-02 volumio[674]: info: Starting Tunnel 1 Nov 11 20:56:04 volumio3beta-hb-xlr-02 volumio[674]: info: Starting Tunnel Connection Checker Nov 11 20:56:04 volumio3beta-hb-xlr-02 volumio[674]: info: Completed starting MyVolumio Plugin Nov 11 20:56:04 volumio3beta-hb-xlr-02 volumio[674]: info: Setting Geolocation for MyVolumio to eu2 Nov 11 20:56:04 volumio3beta-hb-xlr-02 volumio[674]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 11 20:56:04 volumio3beta-hb-xlr-02 volumio[674]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 11 20:56:04 volumio3beta-hb-xlr-02 volumio[674]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 11 20:56:05 volumio3beta-hb-xlr-02 volumio[674]: info: [MyVolumio PluginManager] Device activated, enabling myvolumio plugins... Nov 11 20:56:05 volumio3beta-hb-xlr-02 volumio[674]: info: MyVolumio status changed Nov 11 20:56:05 volumio3beta-hb-xlr-02 volumio[674]: info: Streaming services startup Nov 11 20:56:05 volumio3beta-hb-xlr-02 volumio[674]: info: Re-Starting Streaming Daemon Nov 11 20:56:05 volumio3beta-hb-xlr-02 volumio-streaming-daemon[921]: Restarting daemon Nov 11 20:56:05 volumio3beta-hb-xlr-02 volumio-streaming-daemon[921]: Environment: production Nov 11 20:56:05 volumio3beta-hb-xlr-02 volumio[674]: info: Setting Geolocation for MyVolumio to eu2 Nov 11 20:56:05 volumio3beta-hb-xlr-02 volumio[674]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 11 20:56:05 volumio3beta-hb-xlr-02 volumio[674]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 11 20:56:05 volumio3beta-hb-xlr-02 volumio[674]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 11 20:56:05 volumio3beta-hb-xlr-02 volumio[674]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getHwuuid Nov 11 20:56:05 volumio3beta-hb-xlr-02 volumio[674]: info: [MyVolumio PluginManager] Loading plugin "bluetooth"... Nov 11 20:56:05 volumio3beta-hb-xlr-02 volumio[674]: info: [1636664165871] Starting BluetoothController Nov 11 20:56:06 volumio3beta-hb-xlr-02 volumio[674]: info: [MyVolumio PluginManager] Loading plugin "multiroom"... Nov 11 20:56:06 volumio3beta-hb-xlr-02 volumio-streaming-daemon[921]: Enabled services: Nov 11 20:56:06 volumio3beta-hb-xlr-02 volumio-streaming-daemon[921]: 1) tidal Nov 11 20:56:06 volumio3beta-hb-xlr-02 volumio-streaming-daemon[921]: 2) qobuz Nov 11 20:56:09 volumio3beta-hb-xlr-02 volumio[674]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin multiroom Nov 11 20:56:09 volumio3beta-hb-xlr-02 sudo[951]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod +x /myvolumio/plugins/audio_interface/multiroom/scripts/check_snapcast Nov 11 20:56:09 volumio3beta-hb-xlr-02 sudo[951]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 11 20:56:09 volumio3beta-hb-xlr-02 sudo[951]: pam_unix(sudo:session): session closed for user root Nov 11 20:56:09 volumio3beta-hb-xlr-02 sudo[954]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -rf /tmp/multiroom Nov 11 20:56:09 volumio3beta-hb-xlr-02 sudo[954]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 11 20:56:10 volumio3beta-hb-xlr-02 sudo[954]: pam_unix(sudo:session): session closed for user root Nov 11 20:56:10 volumio3beta-hb-xlr-02 volumio[674]: info: MultiRoom plugin initialized Nov 11 20:56:10 volumio3beta-hb-xlr-02 volumio[674]: STOPPING SNAPCLIENT Nov 11 20:56:10 volumio3beta-hb-xlr-02 volumio[674]: info: [MyVolumio PluginManager] Loading plugin "metavolumio"... Nov 11 20:56:10 volumio3beta-hb-xlr-02 sudo[970]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapclient Nov 11 20:56:10 volumio3beta-hb-xlr-02 sudo[970]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 11 20:56:10 volumio3beta-hb-xlr-02 volumio[674]: info: [MyVolumio PluginManager] Loading plugin "cd_controller"... Nov 11 20:56:10 volumio3beta-hb-xlr-02 sudo[970]: pam_unix(sudo:session): session closed for user root Nov 11 20:56:10 volumio3beta-hb-xlr-02 volumio[674]: info: [MyVolumio PluginManager] Loading plugin "smart_inputs"... Nov 11 20:56:10 volumio3beta-hb-xlr-02 volumio[674]: info: [MyVolumio PluginManager] Loading plugin "hi_res_audio"... Nov 11 20:56:10 volumio3beta-hb-xlr-02 volumio[674]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin hi_res_audio Nov 11 20:56:10 volumio3beta-hb-xlr-02 volumio[674]: info: [MyVolumio PluginManager] Loading plugin "tidal"... Nov 11 20:56:10 volumio3beta-hb-xlr-02 volumio[674]: info: [MyVolumio PluginManager] Loading plugin "qobuz"... Nov 11 20:56:10 volumio3beta-hb-xlr-02 volumio[674]: info: [MyVolumio PluginManager] Loading plugin "tidalconnect"... Nov 11 20:56:10 volumio3beta-hb-xlr-02 volumio[674]: info: Preparing to generate the ALSA configuration file Nov 11 20:56:10 volumio3beta-hb-xlr-02 volumio[674]: info: Updating MyVolumio device info Nov 11 20:56:10 volumio3beta-hb-xlr-02 volumio[674]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 11 20:56:11 volumio3beta-hb-xlr-02 volumio[674]: info: Updating MyVolumio device info Nov 11 20:56:11 volumio3beta-hb-xlr-02 volumio[674]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 11 20:56:11 volumio3beta-hb-xlr-02 volumio[674]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 11 20:56:11 volumio3beta-hb-xlr-02 volumio[674]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 11 20:56:11 volumio3beta-hb-xlr-02 sudo[973]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart sshtunnel.service Nov 11 20:56:11 volumio3beta-hb-xlr-02 sudo[973]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 11 20:56:11 volumio3beta-hb-xlr-02 volumio[674]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Nov 11 20:56:11 volumio3beta-hb-xlr-02 volumio[674]: info: Reading ALSA contributions from plugins. Nov 11 20:56:11 volumio3beta-hb-xlr-02 systemd[1]: Started MyVolumio SSH Tunnel. Nov 11 20:56:11 volumio3beta-hb-xlr-02 sudo[973]: pam_unix(sudo:session): session closed for user root Nov 11 20:56:11 volumio3beta-hb-xlr-02 volumio-streaming-daemon[921]: Environment: production Nov 11 20:56:11 volumio3beta-hb-xlr-02 autossh[976]: port set to 0, monitoring disabled Nov 11 20:56:11 volumio3beta-hb-xlr-02 autossh[976]: starting ssh (count 1) Nov 11 20:56:11 volumio3beta-hb-xlr-02 autossh[976]: ssh child pid is 979 Nov 11 20:56:11 volumio3beta-hb-xlr-02 volumio[674]: info: MRS: SNAPCLIENT STOPPED Nov 11 20:56:11 volumio3beta-hb-xlr-02 volumio[674]: info: Snap server and client installed Nov 11 20:56:11 volumio3beta-hb-xlr-02 volumio[674]: info: Remote SSH Started Nov 11 20:56:11 volumio3beta-hb-xlr-02 volumio[674]: verbose: New Socket.io Connection from localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 12 Nov 11 20:56:12 volumio3beta-hb-xlr-02 volumio[674]: info: Asound.conf file written Nov 11 20:56:12 volumio3beta-hb-xlr-02 sudo[982]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Nov 11 20:56:12 volumio3beta-hb-xlr-02 sudo[982]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 11 20:56:12 volumio3beta-hb-xlr-02 sudo[982]: pam_unix(sudo:session): session closed for user root Nov 11 20:56:12 volumio3beta-hb-xlr-02 volumio[674]: info: Output device has changed, restarting MPD Nov 11 20:56:12 volumio3beta-hb-xlr-02 volumio[674]: info: Output device has changed, restarting Shairport Sync Nov 11 20:56:12 volumio3beta-hb-xlr-02 sudo[987]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 11 20:56:12 volumio3beta-hb-xlr-02 sudo[987]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 11 20:56:12 volumio3beta-hb-xlr-02 sudo[987]: pam_unix(sudo:session): session closed for user root Nov 11 20:56:12 volumio3beta-hb-xlr-02 sudo[989]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 11 20:56:12 volumio3beta-hb-xlr-02 sudo[989]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 11 20:56:12 volumio3beta-hb-xlr-02 systemd[1]: Stopping Music Player Daemon... Nov 11 20:56:12 volumio3beta-hb-xlr-02 volumio[674]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 11 20:56:12 volumio3beta-hb-xlr-02 volumio[674]: info: [MyVolumio PluginManager] Starting plugin audio_interface.bluetooth Nov 11 20:56:12 volumio3beta-hb-xlr-02 volumio[674]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 11 20:56:12 volumio3beta-hb-xlr-02 volumio[674]: info: [MyVolumio PluginManager] Starting plugin audio_interface.multiroom Nov 11 20:56:12 volumio3beta-hb-xlr-02 volumio[674]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , pushMultiRoomStatus Nov 11 20:56:12 volumio3beta-hb-xlr-02 volumio[674]: info: MRS: Pushing multiroomSync output for this device Nov 11 20:56:12 volumio3beta-hb-xlr-02 systemd[1]: mpd.service: Succeeded. Nov 11 20:56:12 volumio3beta-hb-xlr-02 volumio[674]: info: MRS: Pushing multiroomSync output Nov 11 20:56:12 volumio3beta-hb-xlr-02 systemd[1]: Stopped Music Player Daemon. Nov 11 20:56:12 volumio3beta-hb-xlr-02 systemd[1]: Starting Music Player Daemon... Nov 11 20:56:12 volumio3beta-hb-xlr-02 volumio[674]: info: Adding audio output: Nov 11 20:56:12 volumio3beta-hb-xlr-02 volumio[674]: info: [MyVolumio PluginManager] Starting plugin miscellanea.metavolumio Nov 11 20:56:12 volumio3beta-hb-xlr-02 volumio[674]: info: Adding METAVOLUMIO REST API Endpoints Nov 11 20:56:12 volumio3beta-hb-xlr-02 volumio[674]: info: Adding metavolumio REST Endpoint for plugin: miscellanea/metavolumio Nov 11 20:56:12 volumio3beta-hb-xlr-02 volumio[674]: info: [MyVolumio PluginManager] Starting plugin music_service.cd_controller Nov 11 20:56:12 volumio3beta-hb-xlr-02 sudo[997]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/hciconfig hci0 name Volumio3Beta-hb-xlr-02 Nov 11 20:56:12 volumio3beta-hb-xlr-02 volumio[674]: info: Preparing CD Folders Nov 11 20:56:12 volumio3beta-hb-xlr-02 volumio[674]: info: Adding CD REST API Endpoints Nov 11 20:56:12 volumio3beta-hb-xlr-02 volumio[674]: info: Adding cdPostRip REST Endpoint for plugin: music_service/cd_controller Nov 11 20:56:12 volumio3beta-hb-xlr-02 volumio[674]: info: Starting UDEV Watcher for CD Nov 11 20:56:12 volumio3beta-hb-xlr-02 sudo[997]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 11 20:56:12 volumio3beta-hb-xlr-02 volumio[674]: info: Detecting CD presence with UDEV Nov 11 20:56:12 volumio3beta-hb-xlr-02 volumio[674]: info: CoreCommandRouter::executeOnPlugin: networkfs , getUdevDevices Nov 11 20:56:12 volumio3beta-hb-xlr-02 kernel: Bluetooth: Core ver 2.22 Nov 11 20:56:12 volumio3beta-hb-xlr-02 kernel: NET: Registered protocol family 31 Nov 11 20:56:12 volumio3beta-hb-xlr-02 kernel: Bluetooth: HCI device and connection manager initialized Nov 11 20:56:12 volumio3beta-hb-xlr-02 kernel: Bluetooth: HCI socket layer initialized Nov 11 20:56:12 volumio3beta-hb-xlr-02 kernel: Bluetooth: L2CAP socket layer initialized Nov 11 20:56:12 volumio3beta-hb-xlr-02 kernel: Bluetooth: SCO socket layer initialized Nov 11 20:56:12 volumio3beta-hb-xlr-02 sudo[997]: pam_unix(sudo:session): session closed for user root Nov 11 20:56:13 volumio3beta-hb-xlr-02 volumio[674]: info: [MyVolumio PluginManager] Starting plugin music_service.smart_inputs Nov 11 20:56:13 volumio3beta-hb-xlr-02 volumio[674]: info: Adding inputs REST Endpoints Nov 11 20:56:13 volumio3beta-hb-xlr-02 volumio[674]: info: Adding scanAudioInputs REST Endpoint for plugin: music_service/smart_inputs Nov 11 20:56:13 volumio3beta-hb-xlr-02 volumio[674]: info: Scanning Audio Inputs Nov 11 20:56:13 volumio3beta-hb-xlr-02 volumio[674]: info: Checking against Known Cards name Nov 11 20:56:13 volumio3beta-hb-xlr-02 volumio[674]: info: Adding Server instance for streaming Nov 11 20:56:13 volumio3beta-hb-xlr-02 volumio[674]: info: [MyVolumio PluginManager] Starting plugin music_service.hi_res_audio Nov 11 20:56:13 volumio3beta-hb-xlr-02 volumio[674]: error: Hi Res Audio Failed Login: Missing Login Data Nov 11 20:56:13 volumio3beta-hb-xlr-02 volumio[674]: info: [MyVolumio PluginManager] Starting plugin music_service.tidal Nov 11 20:56:13 volumio3beta-hb-xlr-02 volumio[674]: info: [MyVolumio PluginManager] Starting plugin music_service.qobuz Nov 11 20:56:13 volumio3beta-hb-xlr-02 volumio[674]: info: Refreshing QOBUZ token Nov 11 20:56:13 volumio3beta-hb-xlr-02 volumio[674]: info: [MyVolumio PluginManager] Starting plugin music_service.tidalconnect Nov 11 20:56:13 volumio3beta-hb-xlr-02 volumio[674]: info: Stopping AccessToken refresher cron Nov 11 20:56:13 volumio3beta-hb-xlr-02 sudo[1009]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Nov 11 20:56:13 volumio3beta-hb-xlr-02 sudo[1009]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 11 20:56:13 volumio3beta-hb-xlr-02 sudo[1009]: pam_unix(sudo:session): session closed for user root Nov 11 20:56:13 volumio3beta-hb-xlr-02 volumio[674]: info: AccessToken refresher cron started Nov 11 20:56:13 volumio3beta-hb-xlr-02 volumio[674]: info: CoreCommandRouter::volumioGetState Nov 11 20:56:13 volumio3beta-hb-xlr-02 volumio[674]: info: CorePlayQueue::getTrack 0 Nov 11 20:56:13 volumio3beta-hb-xlr-02 volumio[674]: error: Cannot set new Bluetooth Name : Error: Command failed: /usr/bin/sudo /bin/hciconfig hci0 name 'Volumio3Beta-hb-xlr-02' Nov 11 20:56:13 volumio3beta-hb-xlr-02 volumio[674]: Can't get device info: No such device Nov 11 20:56:13 volumio3beta-hb-xlr-02 volumio[674]: info: MPD Permissions set Nov 11 20:56:13 volumio3beta-hb-xlr-02 volumio-streaming-daemon[921]: Environment: production Nov 11 20:56:13 volumio3beta-hb-xlr-02 volumio-streaming-daemon[921]: Environment: production Nov 11 20:56:13 volumio3beta-hb-xlr-02 volumio[674]: info: Fetching Streaming Services browse cache Nov 11 20:56:13 volumio3beta-hb-xlr-02 volumio[674]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 11 20:56:13 volumio3beta-hb-xlr-02 volumio[674]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 11 20:56:13 volumio3beta-hb-xlr-02 volumio[674]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 11 20:56:13 volumio3beta-hb-xlr-02 volumio[674]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 11 20:56:13 volumio3beta-hb-xlr-02 volumio[674]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 11 20:56:13 volumio3beta-hb-xlr-02 volumio[674]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 11 20:56:13 volumio3beta-hb-xlr-02 volumio[674]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 11 20:56:13 volumio3beta-hb-xlr-02 volumio[674]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 11 20:56:13 volumio3beta-hb-xlr-02 volumio[674]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 11 20:56:13 volumio3beta-hb-xlr-02 volumio[674]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 11 20:56:13 volumio3beta-hb-xlr-02 volumio[674]: info: MRS: Adding multiroomSync output Nov 11 20:56:13 volumio3beta-hb-xlr-02 volumio[674]: info: Adding audio output: Nov 11 20:56:13 volumio3beta-hb-xlr-02 volumio[674]: info: MRS: Pushing multiroomSync output Nov 11 20:56:13 volumio3beta-hb-xlr-02 volumio[674]: info: Starting Shairport Sync Nov 11 20:56:14 volumio3beta-hb-xlr-02 sudo[1019]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Nov 11 20:56:14 volumio3beta-hb-xlr-02 sudo[1019]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 11 20:56:14 volumio3beta-hb-xlr-02 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Nov 11 20:56:14 volumio3beta-hb-xlr-02 systemd[1]: shairport-sync.service: Succeeded. Nov 11 20:56:14 volumio3beta-hb-xlr-02 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Nov 11 20:56:14 volumio3beta-hb-xlr-02 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Nov 11 20:56:14 volumio3beta-hb-xlr-02 volumio[674]: info: Access Token successfully retrieved Nov 11 20:56:14 volumio3beta-hb-xlr-02 volumio[674]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 11 20:56:14 volumio3beta-hb-xlr-02 volumio[674]: info: [1636664174143] CoreMusicLibrary::Adding element QOBUZ Nov 11 20:56:14 volumio3beta-hb-xlr-02 volumio[674]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 11 20:56:14 volumio3beta-hb-xlr-02 volumio[674]: Cannot find translation for sourceMedia Servers Nov 11 20:56:14 volumio3beta-hb-xlr-02 volumio[674]: Cannot find translation for sourceQOBUZ Nov 11 20:56:14 volumio3beta-hb-xlr-02 sudo[1019]: pam_unix(sudo:session): session closed for user root Nov 11 20:56:14 volumio3beta-hb-xlr-02 volumio[674]: info: Stopping AccessToken refresher cron for QOBUZ Nov 11 20:56:14 volumio3beta-hb-xlr-02 volumio[674]: info: AccessToken refresher cron started for QOBUZ Nov 11 20:56:14 volumio3beta-hb-xlr-02 volumiossh-tunnel[975]: Warning: Permanently added '[eu1.myvolumio.org]:2222,[188.166.162.121]:2222' (RSA) to the list of known hosts. Nov 11 20:56:14 volumio3beta-hb-xlr-02 volumio[674]: info: Shairport-Sync Started Nov 11 20:56:14 volumio3beta-hb-xlr-02 volumio[674]: info: Fetching Streaming Services browse cache Nov 11 20:56:15 volumio3beta-hb-xlr-02 mpd[1005]: Nov 11 20:56 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Nov 11 20:56:15 volumio3beta-hb-xlr-02 mpd[1005]: Nov 11 20:56 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Nov 11 20:56:15 volumio3beta-hb-xlr-02 mpd[1005]: Nov 11 20:56 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Nov 11 20:56:15 volumio3beta-hb-xlr-02 sudo[1026]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumiobt.service Nov 11 20:56:15 volumio3beta-hb-xlr-02 sudo[1026]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 11 20:56:15 volumio3beta-hb-xlr-02 systemd[1]: Started Music Player Daemon. Nov 11 20:56:15 volumio3beta-hb-xlr-02 sudo[989]: pam_unix(sudo:session): session closed for user root Nov 11 20:56:15 volumio3beta-hb-xlr-02 systemd[1]: Started Volumio Bluetooth Module. Nov 11 20:56:15 volumio3beta-hb-xlr-02 sudo[1026]: pam_unix(sudo:session): session closed for user root Nov 11 20:56:15 volumio3beta-hb-xlr-02 sudo[1031]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /var/lib/bluetooth/* Nov 11 20:56:15 volumio3beta-hb-xlr-02 sudo[1031]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 11 20:56:15 volumio3beta-hb-xlr-02 volumiobt[1030]: /bin/chmod: cannot access '/var/lib/bluetooth/*': No such file or directory Nov 11 20:56:15 volumio3beta-hb-xlr-02 sudo[1031]: pam_unix(sudo:session): session closed for user root Nov 11 20:56:15 volumio3beta-hb-xlr-02 volumio[674]: info: Volumio BT Module successfully started Nov 11 20:56:15 volumio3beta-hb-xlr-02 volumio[674]: error: updateQueue error: null Nov 11 20:56:16 volumio3beta-hb-xlr-02 volumio[674]: info: TidalConnect service stoped! Nov 11 20:56:16 volumio3beta-hb-xlr-02 volumio[674]: info: Adding tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Nov 11 20:56:16 volumio3beta-hb-xlr-02 volumio[674]: info: Adding tc_connect REST Endpoint for plugin: music_service/tidalconnect Nov 11 20:56:16 volumio3beta-hb-xlr-02 sudo[1039]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Nov 11 20:56:16 volumio3beta-hb-xlr-02 sudo[1039]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 11 20:56:16 volumio3beta-hb-xlr-02 systemd[1]: Started Volumio Tidal Connect Service. Nov 11 20:56:16 volumio3beta-hb-xlr-02 sudo[1039]: pam_unix(sudo:session): session closed for user root Nov 11 20:56:16 volumio3beta-hb-xlr-02 sudo[1044]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/killall pulseaudio Nov 11 20:56:16 volumio3beta-hb-xlr-02 sudo[1044]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 11 20:56:16 volumio3beta-hb-xlr-02 volumiobt[1030]: pulseaudio: no process found Nov 11 20:56:16 volumio3beta-hb-xlr-02 sudo[1044]: pam_unix(sudo:session): session closed for user root Nov 11 20:56:17 volumio3beta-hb-xlr-02 dbus-daemon[398]: [system] Activating via systemd: service name='org.freedesktop.RealtimeKit1' unit='rtkit-daemon.service' requested by ':1.16' (uid=1000 pid=1048 comm="/usr/bin/pulseaudio ") Nov 11 20:56:17 volumio3beta-hb-xlr-02 systemd[1]: Starting RealtimeKit Scheduling Policy Service... Nov 11 20:56:17 volumio3beta-hb-xlr-02 volumio[674]: ------------------------------------ BT MESSAGE: BT STATUS: running Nov 11 20:56:17 volumio3beta-hb-xlr-02 volumio[674]: BT PLUGIN MESSAGE: Playing: false Nov 11 20:56:17 volumio3beta-hb-xlr-02 volumio[674]: BT PLUGIN MESSAGE: VOLATILE: Nov 11 20:56:17 volumio3beta-hb-xlr-02 volumio[674]: BT PLUGIN MESSAGE: STATE:undefined Nov 11 20:56:17 volumio3beta-hb-xlr-02 dbus-daemon[398]: [system] Successfully activated service 'org.freedesktop.RealtimeKit1' Nov 11 20:56:17 volumio3beta-hb-xlr-02 systemd[1]: Started RealtimeKit Scheduling Policy Service. Nov 11 20:56:17 volumio3beta-hb-xlr-02 rtkit-daemon[1049]: Successfully called chroot. Nov 11 20:56:17 volumio3beta-hb-xlr-02 rtkit-daemon[1049]: Successfully dropped privileges. Nov 11 20:56:17 volumio3beta-hb-xlr-02 rtkit-daemon[1049]: Successfully limited resources. Nov 11 20:56:17 volumio3beta-hb-xlr-02 rtkit-daemon[1049]: Running. Nov 11 20:56:17 volumio3beta-hb-xlr-02 rtkit-daemon[1049]: Canary thread running. Nov 11 20:56:17 volumio3beta-hb-xlr-02 dbus-daemon[398]: [system] Activating via systemd: service name='org.freedesktop.PolicyKit1' unit='polkit.service' requested by ':1.17' (uid=0 pid=1049 comm="/usr/lib/rtkit/rtkit-daemon ") Nov 11 20:56:17 volumio3beta-hb-xlr-02 rtkit-daemon[1049]: Watchdog thread running. Nov 11 20:56:17 volumio3beta-hb-xlr-02 systemd[1]: Starting Authorization Manager... Nov 11 20:56:17 volumio3beta-hb-xlr-02 volumio[674]: ------------------------------------ BT MESSAGE: BT STATUS: running Nov 11 20:56:17 volumio3beta-hb-xlr-02 volumio[674]: BT PLUGIN MESSAGE: Playing: false Nov 11 20:56:17 volumio3beta-hb-xlr-02 volumio[674]: BT PLUGIN MESSAGE: VOLATILE: Nov 11 20:56:17 volumio3beta-hb-xlr-02 volumio[674]: BT PLUGIN MESSAGE: STATE:undefined Nov 11 20:56:17 volumio3beta-hb-xlr-02 polkitd[1052]: started daemon version 0.105 using authority implementation `local' version `0.105' Nov 11 20:56:17 volumio3beta-hb-xlr-02 dbus-daemon[398]: [system] Successfully activated service 'org.freedesktop.PolicyKit1' Nov 11 20:56:17 volumio3beta-hb-xlr-02 systemd[1]: Started Authorization Manager. Nov 11 20:56:17 volumio3beta-hb-xlr-02 volumio[674]: info: Executing endpoint tc_getconfig Nov 11 20:56:17 volumio3beta-hb-xlr-02 volumio[674]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Nov 11 20:56:17 volumio3beta-hb-xlr-02 volumio[674]: info: MRS: Getting audio outputs on start Nov 11 20:56:17 volumio3beta-hb-xlr-02 vtcs[1041]: STARTING TidalConnect services, version: 1.1.7.5 Nov 11 20:56:17 volumio3beta-hb-xlr-02 vtcs[1041]: STARTED TidalConnect services. Nov 11 20:56:17 volumio3beta-hb-xlr-02 volumio[674]: info: MRS: Requesting all other devices output Nov 11 20:56:17 volumio3beta-hb-xlr-02 volumio[674]: info: MRS: Updating multiroomSync output Nov 11 20:56:17 volumio3beta-hb-xlr-02 volumio[674]: info: Executing endpoint tc_connect Nov 11 20:56:17 volumio3beta-hb-xlr-02 volumio[674]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Nov 11 20:56:17 volumio3beta-hb-xlr-02 volumio[674]: Connecting to TidalConnect. Nov 11 20:56:17 volumio3beta-hb-xlr-02 volumio[674]: info: CoreCommandRouter::servicePushState Nov 11 20:56:17 volumio3beta-hb-xlr-02 volumio[674]: info: CoreStateMachine::pushState Nov 11 20:56:17 volumio3beta-hb-xlr-02 volumio[674]: info: CorePlayQueue::getTrack 0 Nov 11 20:56:17 volumio3beta-hb-xlr-02 volumio[674]: info: CoreCommandRouter::volumioPushState Nov 11 20:56:17 volumio3beta-hb-xlr-02 volumio[674]: info: CorePlayQueue::getTrack 0 Nov 11 20:56:17 volumio3beta-hb-xlr-02 volumio[674]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received tidalconnect Nov 11 20:56:17 volumio3beta-hb-xlr-02 volumio[674]: info: CoreCommandRouter::servicePushState Nov 11 20:56:17 volumio3beta-hb-xlr-02 volumio[674]: info: CoreStateMachine::pushState Nov 11 20:56:17 volumio3beta-hb-xlr-02 volumio[674]: info: CorePlayQueue::getTrack 0 Nov 11 20:56:17 volumio3beta-hb-xlr-02 volumio[674]: info: CoreCommandRouter::volumioPushState Nov 11 20:56:17 volumio3beta-hb-xlr-02 volumio[674]: info: CorePlayQueue::getTrack 0 Nov 11 20:56:17 volumio3beta-hb-xlr-02 volumio[674]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received tidalconnect Nov 11 20:56:17 volumio3beta-hb-xlr-02 volumio[674]: info: MRS: Updating multiroomSync output Nov 11 20:56:17 volumio3beta-hb-xlr-02 volumio[674]: info: MRS: Updating multiroomSync output Nov 11 20:56:18 volumio3beta-hb-xlr-02 dbus-daemon[398]: [system] Activating via systemd: service name='org.bluez' unit='dbus-org.bluez.service' requested by ':1.38' (uid=1000 pid=1048 comm="/usr/bin/pulseaudio ") Nov 11 20:56:18 volumio3beta-hb-xlr-02 systemd[1]: Starting Bluetooth service... Nov 11 20:56:18 volumio3beta-hb-xlr-02 volumio[674]: ------------------------------------ BT MESSAGE: BT STATUS: running Nov 11 20:56:18 volumio3beta-hb-xlr-02 volumio[674]: BT PLUGIN MESSAGE: Playing: false Nov 11 20:56:18 volumio3beta-hb-xlr-02 volumio[674]: BT PLUGIN MESSAGE: VOLATILE: undefined Nov 11 20:56:18 volumio3beta-hb-xlr-02 volumio[674]: BT PLUGIN MESSAGE: STATE:undefined Nov 11 20:56:18 volumio3beta-hb-xlr-02 volumio[674]: verbose: New Socket.io Connection from 192.168.178.172 from 192.168.178.182 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/95.0.4638.69 Safari/537.36 Total Clients: 13 Nov 11 20:56:18 volumio3beta-hb-xlr-02 pulseaudio[1048]: Unable to contact D-Bus: org.freedesktop.DBus.Error.NotSupported: Unable to autolaunch a dbus-daemon without a $DISPLAY for X11 Nov 11 20:56:18 volumio3beta-hb-xlr-02 pulseaudio[1048]: Unable to contact D-Bus: org.freedesktop.DBus.Error.NotSupported: Unable to autolaunch a dbus-daemon without a $DISPLAY for X11 Nov 11 20:56:18 volumio3beta-hb-xlr-02 volumiobt[1030]: Applying permissions Nov 11 20:56:18 volumio3beta-hb-xlr-02 volumiobt[1030]: Setting BT discoverable and pairable Nov 11 20:56:18 volumio3beta-hb-xlr-02 volumio[674]: info: CoreCommandRouter::volumioGetState Nov 11 20:56:18 volumio3beta-hb-xlr-02 volumio[674]: info: CorePlayQueue::getTrack 0 Nov 11 20:56:18 volumio3beta-hb-xlr-02 volumio[674]: info: CoreCommandRouter::volumioGetQueue Nov 11 20:56:18 volumio3beta-hb-xlr-02 volumio[674]: info: CoreStateMachine::getQueue Nov 11 20:56:18 volumio3beta-hb-xlr-02 volumio[674]: info: CorePlayQueue::getQueue Nov 11 20:56:18 volumio3beta-hb-xlr-02 volumio[674]: info: CoreCommandRouter::executeOnPlugin: system , getHwuuid Nov 11 20:56:18 volumio3beta-hb-xlr-02 bluetoothd[1072]: Bluetooth daemon 5.50 Nov 11 20:56:18 volumio3beta-hb-xlr-02 bluetoothd[1072]: Unknown key AutoConnectTimeout for group General in /etc/bluetooth/main.conf Nov 11 20:56:18 volumio3beta-hb-xlr-02 dbus-daemon[398]: [system] Successfully activated service 'org.bluez' Nov 11 20:56:18 volumio3beta-hb-xlr-02 systemd[1]: Started Bluetooth service. Nov 11 20:56:18 volumio3beta-hb-xlr-02 bluetoothd[1072]: Starting SDP server Nov 11 20:56:18 volumio3beta-hb-xlr-02 bluetoothd[1072]: Excluding (cli) sap Nov 11 20:56:18 volumio3beta-hb-xlr-02 kernel: Bluetooth: BNEP (Ethernet Emulation) ver 1.3 Nov 11 20:56:18 volumio3beta-hb-xlr-02 kernel: Bluetooth: BNEP filters: protocol multicast Nov 11 20:56:18 volumio3beta-hb-xlr-02 kernel: Bluetooth: BNEP socket layer initialized Nov 11 20:56:18 volumio3beta-hb-xlr-02 bluetoothd[1072]: Bluetooth management interface 1.14 initialized Nov 11 20:56:18 volumio3beta-hb-xlr-02 dbus-daemon[398]: [system] Activating via systemd: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service' requested by ':1.39' (uid=0 pid=1072 comm="/usr/lib/bluetooth/bluetoothd --noplugin=sap ") Nov 11 20:56:18 volumio3beta-hb-xlr-02 volumiobt[1030]: [bluetooth]# -e power on Nov 11 20:56:18 volumio3beta-hb-xlr-02 volumiobt[1030]: Invalid command in menu main: -e Nov 11 20:56:18 volumio3beta-hb-xlr-02 volumiobt[1030]: Nov 11 20:56:18 volumio3beta-hb-xlr-02 volumiobt[1030]: Use "help" for a list of available commands in a menu. Nov 11 20:56:18 volumio3beta-hb-xlr-02 volumiobt[1030]: Use "menu " if you want to enter any submenu. Nov 11 20:56:18 volumio3beta-hb-xlr-02 volumiobt[1030]: Use "back" if you want to return to menu main. Nov 11 20:56:18 volumio3beta-hb-xlr-02 volumiobt[1030]: [bluetooth]# agent on Nov 11 20:56:18 volumio3beta-hb-xlr-02 volumiobt[1030]: Failed to register agent object Nov 11 20:56:18 volumio3beta-hb-xlr-02 volumiobt[1030]: [bluetooth]# discoverable on Nov 11 20:56:18 volumio3beta-hb-xlr-02 volumiobt[1030]: No default controller available Nov 11 20:56:18 volumio3beta-hb-xlr-02 volumiobt[1030]: [bluetooth]# pairable on Nov 11 20:56:18 volumio3beta-hb-xlr-02 volumiobt[1030]: No default controller available Nov 11 20:56:18 volumio3beta-hb-xlr-02 volumiobt[1030]: [bluetooth]# agent NoInputNoOutput Nov 11 20:56:18 volumio3beta-hb-xlr-02 volumiobt[1030]: Failed to register agent object Nov 11 20:56:18 volumio3beta-hb-xlr-02 volumiobt[1030]: [bluetooth]# default-agent Nov 11 20:56:18 volumio3beta-hb-xlr-02 volumiobt[1030]: No agent is registered Nov 11 20:56:18 volumio3beta-hb-xlr-02 volumiobt[1030]: [bluetooth]# quit Nov 11 20:56:18 volumio3beta-hb-xlr-02 pulseaudio[1048]: Failed to register as a handsfree audio agent with ofono: org.freedesktop.DBus.Error.ServiceUnknown: The name org.ofono was not provided by any .service files Nov 11 20:56:18 volumio3beta-hb-xlr-02 volumiobt[1030]: [59B blob data] Nov 11 20:56:18 volumio3beta-hb-xlr-02 systemd[1]: Starting Hostname Service... Nov 11 20:56:18 volumio3beta-hb-xlr-02 volumio[674]: ------------------------------------ BT MESSAGE: BT STATUS: running Nov 11 20:56:18 volumio3beta-hb-xlr-02 volumio[674]: BT PLUGIN MESSAGE: Playing: false Nov 11 20:56:18 volumio3beta-hb-xlr-02 volumio[674]: BT PLUGIN MESSAGE: VOLATILE: undefined Nov 11 20:56:18 volumio3beta-hb-xlr-02 volumio[674]: BT PLUGIN MESSAGE: STATE:undefined Nov 11 20:56:18 volumio3beta-hb-xlr-02 volumio[893]: ......................................................................................................++++ Nov 11 20:56:18 volumio3beta-hb-xlr-02 dbus-daemon[398]: [system] Successfully activated service 'org.freedesktop.hostname1' Nov 11 20:56:18 volumio3beta-hb-xlr-02 systemd[1]: Started Hostname Service. Nov 11 20:56:19 volumio3beta-hb-xlr-02 volumio[674]: info: TidalConnect service started! Nov 11 20:56:19 volumio3beta-hb-xlr-02 volumio[674]: info: BOOT COMPLETED Nov 11 20:56:19 volumio3beta-hb-xlr-02 volumio[674]: [Metrics] CommandRouter: 44s 643.70ms Nov 11 20:56:19 volumio3beta-hb-xlr-02 volumio[674]: info: CoreCommandRouter::volumiosetStartupVolume Nov 11 20:56:19 volumio3beta-hb-xlr-02 volumio[674]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 11 20:56:19 volumio3beta-hb-xlr-02 volumio[674]: info: VolumeController:: Setting startup Volume 10 Nov 11 20:56:19 volumio3beta-hb-xlr-02 volumio[674]: info: CoreCommandRouter::volumioGetState Nov 11 20:56:19 volumio3beta-hb-xlr-02 volumio[674]: info: CorePlayQueue::getTrack 0 Nov 11 20:56:19 volumio3beta-hb-xlr-02 volumio[674]: info: VolumeController::SetAlsaVolume10 Nov 11 20:56:19 volumio3beta-hb-xlr-02 volumio[674]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 11 20:56:19 volumio3beta-hb-xlr-02 volumio[674]: info: CoreCommandRouter::Close All Modals sent Nov 11 20:56:19 volumio3beta-hb-xlr-02 volumio[674]: info: CoreCommandRouter::Close All Modals sent Nov 11 20:56:19 volumio3beta-hb-xlr-02 volumio[674]: info: CoreStateMachine::pushState Nov 11 20:56:19 volumio3beta-hb-xlr-02 volumio[674]: info: CorePlayQueue::getTrack 0 Nov 11 20:56:19 volumio3beta-hb-xlr-02 volumio[674]: info: CoreCommandRouter::volumioPushState Nov 11 20:56:19 volumio3beta-hb-xlr-02 volumio[674]: info: MRS: Updating multiroomSync output Nov 11 20:56:20 volumio3beta-hb-xlr-02 volumio[674]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Nov 11 20:56:20 volumio3beta-hb-xlr-02 volumio[674]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Nov 11 20:56:20 volumio3beta-hb-xlr-02 volumio[674]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Nov 11 20:56:20 volumio3beta-hb-xlr-02 volumio[674]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 11 20:56:20 volumio3beta-hb-xlr-02 volumio[674]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 11 20:56:24 volumio3beta-hb-xlr-02 volumio[893]: ........................++++ Nov 11 20:56:24 volumio3beta-hb-xlr-02 volumio[893]: e is 65537 (0x010001) Nov 11 20:56:24 volumio3beta-hb-xlr-02 volumio[893]: writing RSA key Nov 11 20:56:30 volumio3beta-hb-xlr-02 ntpd[578]: Soliciting pool server 162.159.200.1 Nov 11 20:56:31 volumio3beta-hb-xlr-02 ntpd[578]: Soliciting pool server 130.60.204.10 Nov 11 20:56:31 volumio3beta-hb-xlr-02 ntpd[578]: Soliciting pool server 192.33.96.102 Nov 11 20:56:32 volumio3beta-hb-xlr-02 ntpd[578]: Soliciting pool server 185.66.109.200 Nov 11 20:56:32 volumio3beta-hb-xlr-02 ntpd[578]: Soliciting pool server 195.186.1.101 Nov 11 20:56:33 volumio3beta-hb-xlr-02 ntpd[578]: Soliciting pool server 82.197.164.46 Nov 11 20:56:33 volumio3beta-hb-xlr-02 ntpd[578]: Soliciting pool server 195.141.190.190 Nov 11 20:56:33 volumio3beta-hb-xlr-02 ntpd[578]: Soliciting pool server 212.51.144.44 Nov 11 20:56:34 volumio3beta-hb-xlr-02 ntpd[578]: Soliciting pool server 212.51.144.46 Nov 11 20:56:34 volumio3beta-hb-xlr-02 ntpd[578]: Soliciting pool server 156.106.214.52 Nov 11 20:56:34 volumio3beta-hb-xlr-02 volumio[674]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Nov 11 20:56:35 volumio3beta-hb-xlr-02 volumio[674]: info: CoreCommandRouter::volumioGetQueue Nov 11 20:56:35 volumio3beta-hb-xlr-02 volumio[674]: info: CoreStateMachine::getQueue Nov 11 20:56:35 volumio3beta-hb-xlr-02 volumio[674]: info: CorePlayQueue::getQueue Nov 11 20:56:35 volumio3beta-hb-xlr-02 ntpd[578]: Soliciting pool server 82.197.188.130 Nov 11 20:56:35 volumio3beta-hb-xlr-02 ntpd[578]: Soliciting pool server 178.209.50.229 Nov 11 20:56:36 volumio3beta-hb-xlr-02 volumio[674]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Nov 11 20:56:36 volumio3beta-hb-xlr-02 volumio[674]: info: TuneIn handleBrowseUri: tunein/location Nov 11 20:56:36 volumio3beta-hb-xlr-02 ntpd[578]: Soliciting pool server 84.16.67.12 Nov 11 20:56:36 volumio3beta-hb-xlr-02 ntpd[578]: Soliciting pool server 2a02:168:6200:8::123 Nov 11 20:56:37 volumio3beta-hb-xlr-02 ntpd[578]: Soliciting pool server 46.165.252.57 Nov 11 20:57:00 volumio3beta-hb-xlr-02 systemd[1]: Starting Daily apt download activities... Nov 11 20:57:00 volumio3beta-hb-xlr-02 volumio[674]: ------------------------------------ BT MESSAGE: BT STATUS: running Nov 11 20:57:00 volumio3beta-hb-xlr-02 volumio[674]: BT PLUGIN MESSAGE: Playing: false Nov 11 20:57:00 volumio3beta-hb-xlr-02 volumio[674]: BT PLUGIN MESSAGE: VOLATILE: undefined Nov 11 20:57:00 volumio3beta-hb-xlr-02 volumio[674]: BT PLUGIN MESSAGE: STATE:undefined Nov 11 20:57:01 volumio3beta-hb-xlr-02 volumio[674]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Nov 11 20:57:01 volumio3beta-hb-xlr-02 volumio[674]: info: TuneIn handleBrowseUri: tunein/browse/?id=r101217 Nov 11 20:57:01 volumio3beta-hb-xlr-02 volumio[674]: info: [TuneIn] Fetching (browse) results For tunein/browse/?id=r101217 Nov 11 20:57:02 volumio3beta-hb-xlr-02 systemd[1]: apt-daily.service: Succeeded. Nov 11 20:57:02 volumio3beta-hb-xlr-02 systemd[1]: Started Daily apt download activities. Nov 11 20:57:09 volumio3beta-hb-xlr-02 volumio[674]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Nov 11 20:57:09 volumio3beta-hb-xlr-02 volumio[674]: info: TuneIn handleBrowseUri: tunein/browse/?id=r100422 Nov 11 20:57:09 volumio3beta-hb-xlr-02 volumio[674]: info: [TuneIn] Fetching (browse) results For tunein/browse/?id=r100422 Nov 11 20:57:11 volumio3beta-hb-xlr-02 volumio[674]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Nov 11 20:57:11 volumio3beta-hb-xlr-02 volumio[674]: info: TuneIn handleBrowseUri: tunein/browse/?id=r101166 Nov 11 20:57:11 volumio3beta-hb-xlr-02 volumio[674]: info: [TuneIn] Fetching (browse) results For tunein/browse/?id=r101166 Nov 11 20:57:11 volumio3beta-hb-xlr-02 systemd[1]: systemd-hostnamed.service: Succeeded. Nov 11 20:57:19 volumio3beta-hb-xlr-02 volumio[674]: info: CoreCommandRouter::volumioReplaceandPlayItems Nov 11 20:57:19 volumio3beta-hb-xlr-02 volumio[674]: info: CoreStateMachine::ClearQueue Nov 11 20:57:19 volumio3beta-hb-xlr-02 volumio[674]: info: CoreStateMachine::stop Nov 11 20:57:19 volumio3beta-hb-xlr-02 volumio[674]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 11 20:57:19 volumio3beta-hb-xlr-02 volumio[674]: verbose: UNSET VOLATILE Nov 11 20:57:19 volumio3beta-hb-xlr-02 volumio[674]: info: CorePlayQueue::clearPlayQueue Nov 11 20:57:19 volumio3beta-hb-xlr-02 volumio[674]: info: CorePlayQueue::saveQueue Nov 11 20:57:19 volumio3beta-hb-xlr-02 volumio[674]: info: CoreCommandRouter::volumioPushState Nov 11 20:57:19 volumio3beta-hb-xlr-02 volumio[674]: info: CoreCommandRouter::volumioPushQueue Nov 11 20:57:19 volumio3beta-hb-xlr-02 volumio[674]: info: CoreStateMachine::addQueueItems Nov 11 20:57:19 volumio3beta-hb-xlr-02 volumio[674]: info: CorePlayQueue::addQueueItems Nov 11 20:57:19 volumio3beta-hb-xlr-02 volumio[674]: info: Adding Item to queue: http://opml.radiotime.com/Tune.ashx?id=s303413 Nov 11 20:57:19 volumio3beta-hb-xlr-02 volumio[674]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Nov 11 20:57:19 volumio3beta-hb-xlr-02 volumio[674]: info: MRS: Updating multiroomSync output Nov 11 20:57:20 volumio3beta-hb-xlr-02 volumio[674]: info: CorePlayQueue::saveQueue Nov 11 20:57:20 volumio3beta-hb-xlr-02 volumio[674]: info: CoreCommandRouter::volumioPushQueue Nov 11 20:57:20 volumio3beta-hb-xlr-02 volumio[674]: info: CoreStateMachine::updateTrackBlock Nov 11 20:57:20 volumio3beta-hb-xlr-02 volumio[674]: info: CorePlayQueue::getTrackBlock Nov 11 20:57:20 volumio3beta-hb-xlr-02 volumio[674]: info: CoreCommandRouter::volumioPlay Nov 11 20:57:20 volumio3beta-hb-xlr-02 volumio[674]: verbose: UNSET VOLATILE Nov 11 20:57:20 volumio3beta-hb-xlr-02 volumio[674]: info: CoreStateMachine::play index 0 Nov 11 20:57:20 volumio3beta-hb-xlr-02 volumio[674]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 11 20:57:20 volumio3beta-hb-xlr-02 volumio[674]: info: CoreStateMachine::stop Nov 11 20:57:20 volumio3beta-hb-xlr-02 volumio[674]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 11 20:57:20 volumio3beta-hb-xlr-02 volumio[674]: verbose: UNSET VOLATILE Nov 11 20:57:20 volumio3beta-hb-xlr-02 volumio[674]: info: CoreStateMachine::play index undefined Nov 11 20:57:20 volumio3beta-hb-xlr-02 volumio[674]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 11 20:57:20 volumio3beta-hb-xlr-02 volumio[674]: info: CorePlayQueue::getTrack 0 Nov 11 20:57:20 volumio3beta-hb-xlr-02 volumio[674]: info: CoreStateMachine::startPlaybackTimer Nov 11 20:57:20 volumio3beta-hb-xlr-02 volumio[674]: info: CorePlayQueue::getTrack 0 Nov 11 20:57:20 volumio3beta-hb-xlr-02 volumio[674]: info: [1636664240138] ControllerWebradio::clearAddPlayTrack Nov 11 20:57:20 volumio3beta-hb-xlr-02 volumio[674]: verbose: ControllerMpd::sendMpdCommand stop Nov 11 20:57:20 volumio3beta-hb-xlr-02 volumio[674]: info: sendMpdCommand stop took 25 milliseconds Nov 11 20:57:20 volumio3beta-hb-xlr-02 volumio[674]: verbose: ControllerMpd::sendMpdCommand clear Nov 11 20:57:20 volumio3beta-hb-xlr-02 volumio[674]: info: Nov 11 20:57:20 volumio3beta-hb-xlr-02 volumio[674]: ---------------------------- MPD announces system playlist update Nov 11 20:57:20 volumio3beta-hb-xlr-02 volumio[674]: info: Ignoring MPD Status Update Nov 11 20:57:20 volumio3beta-hb-xlr-02 volumio[674]: info: sendMpdCommand clear took 7 milliseconds Nov 11 20:57:20 volumio3beta-hb-xlr-02 volumio[674]: verbose: ControllerMpd::sendMpdCommand load "http://icecast.argovia.ch/vrock?DIST=TuneIn&TGT=TuneIn&maxServers=2&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTYzNjY2NDIzOSwiaXNzIjoidGlzcnYifQ.4oF3VOwPqosvN-pMQHdH45xJMZmFG7PcBBeo9QlthEc" Nov 11 20:57:20 volumio3beta-hb-xlr-02 volumio[674]: error: updateQueue error: null Nov 11 20:57:20 volumio3beta-hb-xlr-02 volumio[674]: info: ------------------------------ 12ms Nov 11 20:57:20 volumio3beta-hb-xlr-02 volumio[674]: verbose: ControllerMpd::sendMpdCommand add "http://icecast.argovia.ch/vrock?DIST=TuneIn&TGT=TuneIn&maxServers=2&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTYzNjY2NDIzOSwiaXNzIjoidGlzcnYifQ.4oF3VOwPqosvN-pMQHdH45xJMZmFG7PcBBeo9QlthEc" Nov 11 20:57:20 volumio3beta-hb-xlr-02 volumio[674]: info: Nov 11 20:57:20 volumio3beta-hb-xlr-02 volumio[674]: ---------------------------- MPD announces system playlist update Nov 11 20:57:20 volumio3beta-hb-xlr-02 volumio[674]: info: Ignoring MPD Status Update Nov 11 20:57:20 volumio3beta-hb-xlr-02 volumio[674]: info: sendMpdCommand add "http://icecast.argovia.ch/vrock?DIST=TuneIn&TGT=TuneIn&maxServers=2&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTYzNjY2NDIzOSwiaXNzIjoidGlzcnYifQ.4oF3VOwPqosvN-pMQHdH45xJMZmFG7PcBBeo9QlthEc" took 4 milliseconds Nov 11 20:57:20 volumio3beta-hb-xlr-02 volumio[674]: info: CoreStateMachine::setConsumeUpdateService mpd Nov 11 20:57:20 volumio3beta-hb-xlr-02 volumio[674]: verbose: ControllerMpd::sendMpdCommand play Nov 11 20:57:20 volumio3beta-hb-xlr-02 volumio[674]: info: ------------------------------ 10ms Nov 11 20:57:20 volumio3beta-hb-xlr-02 volumio[674]: info: sendMpdCommand play took 7 milliseconds Nov 11 20:57:21 volumio3beta-hb-xlr-02 volumio[674]: info: Nov 11 20:57:21 volumio3beta-hb-xlr-02 volumio[674]: ---------------------------- MPD announces state update: player Nov 11 20:57:21 volumio3beta-hb-xlr-02 volumio[674]: info: ControllerMpd::getState Nov 11 20:57:21 volumio3beta-hb-xlr-02 volumio[674]: verbose: ControllerMpd::sendMpdCommand status Nov 11 20:57:21 volumio3beta-hb-xlr-02 volumio[674]: info: Nov 11 20:57:21 volumio3beta-hb-xlr-02 volumio[674]: ---------------------------- MPD announces system playlist update Nov 11 20:57:21 volumio3beta-hb-xlr-02 volumio[674]: info: Ignoring MPD Status Update Nov 11 20:57:21 volumio3beta-hb-xlr-02 volumio[674]: info: Nov 11 20:57:21 volumio3beta-hb-xlr-02 volumio[674]: ---------------------------- MPD announces state update: player Nov 11 20:57:21 volumio3beta-hb-xlr-02 volumio[674]: info: sendMpdCommand status took 64 milliseconds Nov 11 20:57:21 volumio3beta-hb-xlr-02 volumio[674]: info: ControllerMpd::getState Nov 11 20:57:21 volumio3beta-hb-xlr-02 volumio[674]: verbose: ControllerMpd::sendMpdCommand status Nov 11 20:57:21 volumio3beta-hb-xlr-02 volumio[674]: verbose: ControllerMpd::parseState Nov 11 20:57:21 volumio3beta-hb-xlr-02 volumio[674]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 11 20:57:21 volumio3beta-hb-xlr-02 volumio[674]: info: ------------------------------ 27ms Nov 11 20:57:21 volumio3beta-hb-xlr-02 volumio[674]: info: sendMpdCommand status took 10 milliseconds Nov 11 20:57:21 volumio3beta-hb-xlr-02 volumio[674]: info: sendMpdCommand playlistinfo took 7 milliseconds Nov 11 20:57:21 volumio3beta-hb-xlr-02 volumio[674]: verbose: ControllerMpd::parseState Nov 11 20:57:21 volumio3beta-hb-xlr-02 volumio[674]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 11 20:57:21 volumio3beta-hb-xlr-02 volumio[674]: verbose: ControllerMpd::parseTrackInfo Nov 11 20:57:21 volumio3beta-hb-xlr-02 volumio[674]: info: ControllerMpd::pushState Nov 11 20:57:21 volumio3beta-hb-xlr-02 volumio[674]: info: CoreCommandRouter::servicePushState Nov 11 20:57:21 volumio3beta-hb-xlr-02 volumio[674]: info: CorePlayQueue::getTrack 0 Nov 11 20:57:21 volumio3beta-hb-xlr-02 volumio[674]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"BILLY IDOL - CRADLE OF LOVE","artist":"V-Rock.","album":null,"uri":"http://icecast.argovia.ch/vrock?DIST=TuneIn&TGT=TuneIn&maxServers=2&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTYzNjY2NDIzOSwiaXNzIjoidGlzcnYifQ.4oF3VOwPqosvN-pMQHdH45xJMZmFG7PcBBeo9QlthEc","trackType":""} Nov 11 20:57:21 volumio3beta-hb-xlr-02 volumio[674]: verbose: CURRENT POSITION 0 Nov 11 20:57:21 volumio3beta-hb-xlr-02 volumio[674]: info: CoreStateMachine::syncState stateService play Nov 11 20:57:21 volumio3beta-hb-xlr-02 volumio[674]: info: CoreStateMachine::syncState currentStatus stop Nov 11 20:57:21 volumio3beta-hb-xlr-02 volumio[674]: info: CoreStateMachine::pushState Nov 11 20:57:21 volumio3beta-hb-xlr-02 volumio[674]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 11 20:57:21 volumio3beta-hb-xlr-02 volumio[674]: info: CoreCommandRouter::volumioPushState Nov 11 20:57:21 volumio3beta-hb-xlr-02 volumio[674]: info: ------------------------------ 129ms Nov 11 20:57:21 volumio3beta-hb-xlr-02 volumio[674]: info: sendMpdCommand playlistinfo took 47 milliseconds Nov 11 20:57:21 volumio3beta-hb-xlr-02 volumio[674]: verbose: ControllerMpd::parseTrackInfo Nov 11 20:57:21 volumio3beta-hb-xlr-02 volumio[674]: info: ControllerMpd::pushState Nov 11 20:57:21 volumio3beta-hb-xlr-02 volumio[674]: info: CoreCommandRouter::servicePushState Nov 11 20:57:21 volumio3beta-hb-xlr-02 volumio[674]: info: CorePlayQueue::getTrack 0 Nov 11 20:57:21 volumio3beta-hb-xlr-02 volumio[674]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1611,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"BILLY IDOL - CRADLE OF LOVE","artist":"V-Rock.","album":null,"uri":"http://icecast.argovia.ch/vrock?DIST=TuneIn&TGT=TuneIn&maxServers=2&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTYzNjY2NDIzOSwiaXNzIjoidGlzcnYifQ.4oF3VOwPqosvN-pMQHdH45xJMZmFG7PcBBeo9QlthEc","trackType":""} Nov 11 20:57:21 volumio3beta-hb-xlr-02 volumio[674]: verbose: CURRENT POSITION 0 Nov 11 20:57:21 volumio3beta-hb-xlr-02 volumio[674]: info: CoreStateMachine::syncState stateService play Nov 11 20:57:21 volumio3beta-hb-xlr-02 volumio[674]: info: CoreStateMachine::syncState currentStatus play Nov 11 20:57:21 volumio3beta-hb-xlr-02 volumio[674]: info: Received an update from plugin. extracting info from payload Nov 11 20:57:21 volumio3beta-hb-xlr-02 volumio[674]: info: CoreStateMachine::pushState Nov 11 20:57:21 volumio3beta-hb-xlr-02 volumio[674]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 11 20:57:21 volumio3beta-hb-xlr-02 volumio[674]: info: CoreCommandRouter::volumioPushState Nov 11 20:57:21 volumio3beta-hb-xlr-02 volumio[674]: info: CoreStateMachine::pushState Nov 11 20:57:21 volumio3beta-hb-xlr-02 volumio[674]: info: CoreCommandRouter::volumioPushState Nov 11 20:57:21 volumio3beta-hb-xlr-02 volumio[674]: info: ------------------------------ 143ms Nov 11 20:57:21 volumio3beta-hb-xlr-02 volumio[674]: info: MRS: Updating multiroomSync output Nov 11 20:57:21 volumio3beta-hb-xlr-02 volumio[674]: info: MRS: Updating multiroomSync output Nov 11 20:57:21 volumio3beta-hb-xlr-02 volumio[674]: info: MRS: Updating multiroomSync output Nov 11 20:57:42 volumio3beta-hb-xlr-02 volumio[674]: info: Discovery: adding 42227f6c-9990-4e8e-b4e2-0df30a5bf037 Nov 11 20:57:42 volumio3beta-hb-xlr-02 volumio[674]: info: mDNS: Found device Volumio3Beta-HB-Dac01 Nov 11 20:57:42 volumio3beta-hb-xlr-02 volumio[674]: info: MRS: Pushing multiroomSync output update for this device Nov 11 20:57:42 volumio3beta-hb-xlr-02 volumio[674]: info: MRS: Pushing multiroomSync output Nov 11 20:57:42 volumio3beta-hb-xlr-02 volumio[674]: info: MRS: Updating multiroomSync output Nov 11 20:57:52 volumio3beta-hb-xlr-02 volumio[674]: info: MRS: Updating multiroomSync output Nov 11 20:57:52 volumio3beta-hb-xlr-02 volumio[674]: info: MRS: Updating multiroomSync output Nov 11 20:57:52 volumio3beta-hb-xlr-02 volumio[674]: info: MRS: Pushing audio output on request Nov 11 20:57:52 volumio3beta-hb-xlr-02 volumio[674]: info: MRS: Pushing multiroomSync output Nov 11 20:57:52 volumio3beta-hb-xlr-02 volumio[674]: info: MRS: Pushing multiroomSync output update for this device Nov 11 20:57:52 volumio3beta-hb-xlr-02 volumio[674]: info: MRS: Pushing multiroomSync output Nov 11 20:57:54 volumio3beta-hb-xlr-02 volumio[674]: info: MRS: Updating multiroomSync output Nov 11 20:57:54 volumio3beta-hb-xlr-02 volumio[674]: info: MRS: Updating multiroomSync output Nov 11 20:57:54 volumio3beta-hb-xlr-02 volumio[674]: info: MRS: Updating multiroomSync output Nov 11 20:57:54 volumio3beta-hb-xlr-02 volumio[674]: info: MRS: Pushing multiroomSync output update for this device Nov 11 20:57:54 volumio3beta-hb-xlr-02 volumio[674]: info: MRS: Pushing multiroomSync output Nov 11 20:57:54 volumio3beta-hb-xlr-02 volumio[674]: info: MRS: Pushing multiroomSync output update for this device Nov 11 20:57:54 volumio3beta-hb-xlr-02 volumio[674]: info: MRS: Pushing multiroomSync output Nov 11 20:57:54 volumio3beta-hb-xlr-02 volumio[674]: info: MRS: Pushing multiroomSync output update for this device Nov 11 20:57:54 volumio3beta-hb-xlr-02 volumio[674]: info: MRS: Pushing multiroomSync output Nov 11 20:57:56 volumio3beta-hb-xlr-02 volumio[674]: info: MRS: Updating multiroomSync output Nov 11 20:57:56 volumio3beta-hb-xlr-02 volumio[674]: info: MRS: Pushing multiroomSync output update for this device Nov 11 20:57:56 volumio3beta-hb-xlr-02 volumio[674]: info: MRS: Pushing multiroomSync output Nov 11 20:57:57 volumio3beta-hb-xlr-02 volumio[674]: verbose: New Socket.io Connection from 192.168.178.172:3000 from 192.168.178.153 UA: node-XMLHttpRequest Total Clients: 7 Nov 11 20:57:58 volumio3beta-hb-xlr-02 volumio[674]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , initSocket Nov 11 20:57:58 volumio3beta-hb-xlr-02 volumio[674]: info: CoreCommandRouter::volumioGetState Nov 11 20:58:39 volumio3beta-hb-xlr-02 volumio[674]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Nov 11 20:58:40 volumio3beta-hb-xlr-02 volumio[674]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Nov 11 20:58:45 volumio3beta-hb-xlr-02 volumio[674]: info: CoreCommandRouter::volumioReplaceandPlayItems Nov 11 20:58:45 volumio3beta-hb-xlr-02 volumio[674]: info: CoreStateMachine::ClearQueue Nov 11 20:58:45 volumio3beta-hb-xlr-02 volumio[674]: info: CoreStateMachine::stop Nov 11 20:58:45 volumio3beta-hb-xlr-02 volumio[674]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 11 20:58:45 volumio3beta-hb-xlr-02 volumio[674]: verbose: UNSET VOLATILE Nov 11 20:58:45 volumio3beta-hb-xlr-02 volumio[674]: info: CoreStateMachine::stPlaybackTimer Nov 11 20:58:45 volumio3beta-hb-xlr-02 volumio[674]: info: CoreStateMachine::updateTrackBlock Nov 11 20:58:45 volumio3beta-hb-xlr-02 volumio[674]: info: CorePlayQueue::getTrackBlock Nov 11 20:58:45 volumio3beta-hb-xlr-02 volumio[674]: info: CoreStateMachine::pushState Nov 11 20:58:45 volumio3beta-hb-xlr-02 volumio[674]: info: CorePlayQueue::getTrack 0 Nov 11 20:58:45 volumio3beta-hb-xlr-02 volumio[674]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 11 20:58:45 volumio3beta-hb-xlr-02 volumio[674]: info: CoreCommandRouter::volumioPushState Nov 11 20:58:45 volumio3beta-hb-xlr-02 volumio[674]: info: CoreStateMachine::serviceStop Nov 11 20:58:45 volumio3beta-hb-xlr-02 volumio[674]: info: CorePlayQueue::getTrack 0 Nov 11 20:58:45 volumio3beta-hb-xlr-02 volumio[674]: info: CoreCommandRouter::serviceStop Nov 11 20:58:45 volumio3beta-hb-xlr-02 volumio[674]: info: [1636664325874] ControllerWebradio::stop Nov 11 20:58:45 volumio3beta-hb-xlr-02 volumio[674]: verbose: ControllerMpd::sendMpdCommand stop Nov 11 20:58:45 volumio3beta-hb-xlr-02 volumio[674]: info: CorePlayQueue::clearPlayQueue Nov 11 20:58:45 volumio3beta-hb-xlr-02 volumio[674]: info: CorePlayQueue::saveQueue Nov 11 20:58:45 volumio3beta-hb-xlr-02 volumio[674]: info: CoreCommandRouter::volumioPushState Nov 11 20:58:45 volumio3beta-hb-xlr-02 volumio[674]: info: CoreCommandRouter::volumioPushQueue Nov 11 20:58:45 volumio3beta-hb-xlr-02 volumio[674]: info: CoreStateMachine::addQueueItems Nov 11 20:58:45 volumio3beta-hb-xlr-02 volumio[674]: info: CorePlayQueue::addQueueItems Nov 11 20:58:45 volumio3beta-hb-xlr-02 volumio[674]: info: Adding Item to queue: http://opml.radiotime.com/Tune.ashx?id=s303413 Nov 11 20:58:45 volumio3beta-hb-xlr-02 volumio[674]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Nov 11 20:58:45 volumio3beta-hb-xlr-02 volumio[674]: info: CorePlayQueue::saveQueue Nov 11 20:58:45 volumio3beta-hb-xlr-02 volumio[674]: info: CoreCommandRouter::volumioPushQueue Nov 11 20:58:45 volumio3beta-hb-xlr-02 volumio[674]: info: CoreStateMachine::updateTrackBlock Nov 11 20:58:45 volumio3beta-hb-xlr-02 volumio[674]: info: CorePlayQueue::getTrackBlock Nov 11 20:58:45 volumio3beta-hb-xlr-02 volumio[674]: info: CoreCommandRouter::volumioPlay Nov 11 20:58:45 volumio3beta-hb-xlr-02 volumio[674]: verbose: UNSET VOLATILE Nov 11 20:58:45 volumio3beta-hb-xlr-02 volumio[674]: info: CoreStateMachine::play index 0 Nov 11 20:58:45 volumio3beta-hb-xlr-02 volumio[674]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 11 20:58:45 volumio3beta-hb-xlr-02 volumio[674]: info: CoreStateMachine::stop Nov 11 20:58:45 volumio3beta-hb-xlr-02 volumio[674]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 11 20:58:45 volumio3beta-hb-xlr-02 volumio[674]: verbose: UNSET VOLATILE Nov 11 20:58:45 volumio3beta-hb-xlr-02 volumio[674]: info: CoreStateMachine::play index undefined Nov 11 20:58:45 volumio3beta-hb-xlr-02 volumio[674]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 11 20:58:45 volumio3beta-hb-xlr-02 volumio[674]: info: CorePlayQueue::getTrack 0 Nov 11 20:58:45 volumio3beta-hb-xlr-02 volumio[674]: info: CoreStateMachine::startPlaybackTimer Nov 11 20:58:45 volumio3beta-hb-xlr-02 volumio[674]: info: CorePlayQueue::getTrack 0 Nov 11 20:58:45 volumio3beta-hb-xlr-02 volumio[674]: info: [1636664325933] ControllerWebradio::clearAddPlayTrack Nov 11 20:58:45 volumio3beta-hb-xlr-02 volumio[674]: verbose: ControllerMpd::sendMpdCommand stop Nov 11 20:58:45 volumio3beta-hb-xlr-02 volumio[674]: info: Nov 11 20:58:45 volumio3beta-hb-xlr-02 volumio[674]: ---------------------------- MPD announces state update: player Nov 11 20:58:45 volumio3beta-hb-xlr-02 volumio[674]: info: sendMpdCommand stop took 78 milliseconds Nov 11 20:58:45 volumio3beta-hb-xlr-02 volumio[674]: info: ControllerMpd::getState Nov 11 20:58:45 volumio3beta-hb-xlr-02 volumio[674]: verbose: ControllerMpd::sendMpdCommand status Nov 11 20:58:45 volumio3beta-hb-xlr-02 volumio[674]: info: sendMpdCommand stop took 21 milliseconds Nov 11 20:58:45 volumio3beta-hb-xlr-02 volumio[674]: verbose: ControllerMpd::sendMpdCommand clear Nov 11 20:58:45 volumio3beta-hb-xlr-02 volumio[674]: info: Nov 11 20:58:45 volumio3beta-hb-xlr-02 volumio[674]: ---------------------------- MPD announces system playlist update Nov 11 20:58:45 volumio3beta-hb-xlr-02 volumio[674]: info: Ignoring MPD Status Update Nov 11 20:58:45 volumio3beta-hb-xlr-02 volumio[674]: info: sendMpdCommand status took 31 milliseconds Nov 11 20:58:45 volumio3beta-hb-xlr-02 volumio[674]: info: sendMpdCommand clear took 28 milliseconds Nov 11 20:58:45 volumio3beta-hb-xlr-02 volumio[674]: verbose: ControllerMpd::parseState Nov 11 20:58:45 volumio3beta-hb-xlr-02 volumio[674]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 11 20:58:45 volumio3beta-hb-xlr-02 volumio[674]: verbose: ControllerMpd::sendMpdCommand load "http://icecast.argovia.ch/vrock?DIST=TuneIn&TGT=TuneIn&maxServers=2&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTYzNjY2NDIzOSwiaXNzIjoidGlzcnYifQ.4oF3VOwPqosvN-pMQHdH45xJMZmFG7PcBBeo9QlthEc" Nov 11 20:58:45 volumio3beta-hb-xlr-02 volumio[674]: error: updateQueue error: null Nov 11 20:58:46 volumio3beta-hb-xlr-02 volumio[674]: info: ------------------------------ 18ms Nov 11 20:58:46 volumio3beta-hb-xlr-02 volumio[674]: info: sendMpdCommand playlistinfo took 12 milliseconds Nov 11 20:58:46 volumio3beta-hb-xlr-02 volumio[674]: verbose: ControllerMpd::parseTrackInfo Nov 11 20:58:46 volumio3beta-hb-xlr-02 volumio[674]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Nov 11 20:58:46 volumio3beta-hb-xlr-02 volumio[674]: info: ------------------------------ 56ms Nov 11 20:58:46 volumio3beta-hb-xlr-02 volumio[674]: info: MRS: Updating multiroomSync output Nov 11 20:58:46 volumio3beta-hb-xlr-02 volumio[674]: info: MRS: Updating multiroomSync output Nov 11 20:58:46 volumio3beta-hb-xlr-02 volumio[674]: verbose: ControllerMpd::sendMpdCommand add "http://icecast.argovia.ch/vrock?DIST=TuneIn&TGT=TuneIn&maxServers=2&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTYzNjY2NDIzOSwiaXNzIjoidGlzcnYifQ.4oF3VOwPqosvN-pMQHdH45xJMZmFG7PcBBeo9QlthEc" Nov 11 20:58:46 volumio3beta-hb-xlr-02 volumio[674]: info: Nov 11 20:58:46 volumio3beta-hb-xlr-02 volumio[674]: ---------------------------- MPD announces system playlist update Nov 11 20:58:46 volumio3beta-hb-xlr-02 volumio[674]: info: Ignoring MPD Status Update Nov 11 20:58:46 volumio3beta-hb-xlr-02 volumio[674]: info: sendMpdCommand add "http://icecast.argovia.ch/vrock?DIST=TuneIn&TGT=TuneIn&maxServers=2&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTYzNjY2NDIzOSwiaXNzIjoidGlzcnYifQ.4oF3VOwPqosvN-pMQHdH45xJMZmFG7PcBBeo9QlthEc" took 4 milliseconds Nov 11 20:58:46 volumio3beta-hb-xlr-02 volumio[674]: info: CoreStateMachine::setConsumeUpdateService mpd Nov 11 20:58:46 volumio3beta-hb-xlr-02 volumio[674]: verbose: ControllerMpd::sendMpdCommand play Nov 11 20:58:46 volumio3beta-hb-xlr-02 volumio[674]: info: ------------------------------ 10ms Nov 11 20:58:46 volumio3beta-hb-xlr-02 volumio[674]: info: sendMpdCommand play took 6 milliseconds Nov 11 20:58:47 volumio3beta-hb-xlr-02 volumio[674]: info: Nov 11 20:58:47 volumio3beta-hb-xlr-02 volumio[674]: ---------------------------- MPD announces state update: player Nov 11 20:58:47 volumio3beta-hb-xlr-02 volumio[674]: info: ControllerMpd::getState Nov 11 20:58:47 volumio3beta-hb-xlr-02 volumio[674]: verbose: ControllerMpd::sendMpdCommand status Nov 11 20:58:47 volumio3beta-hb-xlr-02 volumio[674]: info: Nov 11 20:58:47 volumio3beta-hb-xlr-02 volumio[674]: ---------------------------- MPD announces system playlist update Nov 11 20:58:47 volumio3beta-hb-xlr-02 volumio[674]: info: Ignoring MPD Status Update Nov 11 20:58:47 volumio3beta-hb-xlr-02 volumio[674]: info: Nov 11 20:58:47 volumio3beta-hb-xlr-02 volumio[674]: ---------------------------- MPD announces state update: player Nov 11 20:58:47 volumio3beta-hb-xlr-02 volumio[674]: info: sendMpdCommand status took 44 milliseconds Nov 11 20:58:47 volumio3beta-hb-xlr-02 volumio[674]: info: ControllerMpd::getState Nov 11 20:58:47 volumio3beta-hb-xlr-02 volumio[674]: verbose: ControllerMpd::sendMpdCommand status Nov 11 20:58:47 volumio3beta-hb-xlr-02 volumio[674]: verbose: ControllerMpd::parseState Nov 11 20:58:47 volumio3beta-hb-xlr-02 volumio[674]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 11 20:58:47 volumio3beta-hb-xlr-02 volumio[674]: info: ------------------------------ 13ms Nov 11 20:58:47 volumio3beta-hb-xlr-02 volumio[674]: info: sendMpdCommand status took 10 milliseconds Nov 11 20:58:47 volumio3beta-hb-xlr-02 volumio[674]: info: sendMpdCommand playlistinfo took 9 milliseconds Nov 11 20:58:47 volumio3beta-hb-xlr-02 volumio[674]: verbose: ControllerMpd::parseState Nov 11 20:58:47 volumio3beta-hb-xlr-02 volumio[674]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 11 20:58:47 volumio3beta-hb-xlr-02 volumio[674]: verbose: ControllerMpd::parseTrackInfo Nov 11 20:58:47 volumio3beta-hb-xlr-02 volumio[674]: info: ControllerMpd::pushState Nov 11 20:58:47 volumio3beta-hb-xlr-02 volumio[674]: info: CoreCommandRouter::servicePushState Nov 11 20:58:47 volumio3beta-hb-xlr-02 volumio[674]: info: CorePlayQueue::getTrack 0 Nov 11 20:58:47 volumio3beta-hb-xlr-02 volumio[674]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"BILLY IDOL - CRADLE OF LOVE","artist":"V-Rock.","album":null,"uri":"http://icecast.argovia.ch/vrock?DIST=TuneIn&TGT=TuneIn&maxServers=2&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTYzNjY2NDIzOSwiaXNzIjoidGlzcnYifQ.4oF3VOwPqosvN-pMQHdH45xJMZmFG7PcBBeo9QlthEc","trackType":""} Nov 11 20:58:47 volumio3beta-hb-xlr-02 volumio[674]: verbose: CURRENT POSITION 0 Nov 11 20:58:47 volumio3beta-hb-xlr-02 volumio[674]: info: CoreStateMachine::syncState stateService play Nov 11 20:58:47 volumio3beta-hb-xlr-02 volumio[674]: info: CoreStateMachine::syncState currentStatus stop Nov 11 20:58:47 volumio3beta-hb-xlr-02 volumio[674]: info: CoreStateMachine::pushState Nov 11 20:58:47 volumio3beta-hb-xlr-02 volumio[674]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 11 20:58:47 volumio3beta-hb-xlr-02 volumio[674]: info: CoreCommandRouter::volumioPushState Nov 11 20:58:47 volumio3beta-hb-xlr-02 volumio[674]: info: ------------------------------ 93ms Nov 11 20:58:47 volumio3beta-hb-xlr-02 volumio[674]: info: sendMpdCommand playlistinfo took 31 milliseconds Nov 11 20:58:47 volumio3beta-hb-xlr-02 volumio[674]: verbose: ControllerMpd::parseTrackInfo Nov 11 20:58:47 volumio3beta-hb-xlr-02 volumio[674]: info: ControllerMpd::pushState Nov 11 20:58:47 volumio3beta-hb-xlr-02 volumio[674]: info: CoreCommandRouter::servicePushState Nov 11 20:58:47 volumio3beta-hb-xlr-02 volumio[674]: info: CorePlayQueue::getTrack 0 Nov 11 20:58:47 volumio3beta-hb-xlr-02 volumio[674]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1085,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"BILLY IDOL - CRADLE OF LOVE","artist":"V-Rock.","album":null,"uri":"http://icecast.argovia.ch/vrock?DIST=TuneIn&TGT=TuneIn&maxServers=2&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTYzNjY2NDIzOSwiaXNzIjoidGlzcnYifQ.4oF3VOwPqosvN-pMQHdH45xJMZmFG7PcBBeo9QlthEc","trackType":""} Nov 11 20:58:47 volumio3beta-hb-xlr-02 volumio[674]: verbose: CURRENT POSITION 0 Nov 11 20:58:47 volumio3beta-hb-xlr-02 volumio[674]: info: CoreStateMachine::syncState stateService play Nov 11 20:58:47 volumio3beta-hb-xlr-02 volumio[674]: info: CoreStateMachine::syncState currentStatus play Nov 11 20:58:47 volumio3beta-hb-xlr-02 volumio[674]: info: Received an update from plugin. extracting info from payload Nov 11 20:58:47 volumio3beta-hb-xlr-02 volumio[674]: info: CoreStateMachine::pushState Nov 11 20:58:47 volumio3beta-hb-xlr-02 volumio[674]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 11 20:58:47 volumio3beta-hb-xlr-02 volumio[674]: info: CoreCommandRouter::volumioPushState Nov 11 20:58:47 volumio3beta-hb-xlr-02 volumio[674]: info: CoreStateMachine::pushState Nov 11 20:58:47 volumio3beta-hb-xlr-02 volumio[674]: info: CoreCommandRouter::volumioPushState Nov 11 20:58:47 volumio3beta-hb-xlr-02 volumio[674]: info: ------------------------------ 84ms Nov 11 20:58:47 volumio3beta-hb-xlr-02 volumio[674]: info: MRS: Updating multiroomSync output Nov 11 20:58:47 volumio3beta-hb-xlr-02 volumio[674]: info: MRS: Updating multiroomSync output Nov 11 20:58:47 volumio3beta-hb-xlr-02 volumio[674]: info: MRS: Updating multiroomSync output Nov 11 21:00:27 volumio3beta-hb-xlr-02 volumio[674]: info: Tunnel connection is inactive, restarting it Nov 11 21:00:27 volumio3beta-hb-xlr-02 volumio[674]: info: Starting Tunnel 1 Nov 11 21:00:27 volumio3beta-hb-xlr-02 volumio[674]: info: Starting Tunnel Connection Checker Nov 11 21:00:28 volumio3beta-hb-xlr-02 sudo[1178]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart sshtunnel.service Nov 11 21:00:28 volumio3beta-hb-xlr-02 sudo[1178]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 11 21:00:28 volumio3beta-hb-xlr-02 autossh[976]: received signal to exit (15) Nov 11 21:00:28 volumio3beta-hb-xlr-02 systemd[1]: Stopping MyVolumio SSH Tunnel... Nov 11 21:00:28 volumio3beta-hb-xlr-02 systemd[1]: sshtunnel.service: Main process exited, code=killed, status=15/TERM Nov 11 21:00:28 volumio3beta-hb-xlr-02 systemd[1]: sshtunnel.service: Succeeded. Nov 11 21:00:28 volumio3beta-hb-xlr-02 systemd[1]: Stopped MyVolumio SSH Tunnel. Nov 11 21:00:28 volumio3beta-hb-xlr-02 systemd[1]: Started MyVolumio SSH Tunnel. Nov 11 21:00:28 volumio3beta-hb-xlr-02 sudo[1178]: pam_unix(sudo:session): session closed for user root Nov 11 21:00:28 volumio3beta-hb-xlr-02 volumio[674]: ------------------------------------ BT MESSAGE: BT STATUS: running Nov 11 21:00:28 volumio3beta-hb-xlr-02 volumio[674]: BT PLUGIN MESSAGE: Playing: false Nov 11 21:00:28 volumio3beta-hb-xlr-02 volumio[674]: BT PLUGIN MESSAGE: VOLATILE: undefined Nov 11 21:00:28 volumio3beta-hb-xlr-02 volumio[674]: BT PLUGIN MESSAGE: STATE:undefined Nov 11 21:00:28 volumio3beta-hb-xlr-02 volumio[674]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Nov 11 21:00:28 volumio3beta-hb-xlr-02 volumio[674]: BT PLUGIN MESSAGE: Playing: false Nov 11 21:00:28 volumio3beta-hb-xlr-02 volumio[674]: BT PLUGIN MESSAGE: VOLATILE: undefined Nov 11 21:00:28 volumio3beta-hb-xlr-02 volumio[674]: BT PLUGIN MESSAGE: STATE:undefined Nov 11 21:00:28 volumio3beta-hb-xlr-02 autossh[1181]: port set to 0, monitoring disabled Nov 11 21:00:28 volumio3beta-hb-xlr-02 autossh[1181]: starting ssh (count 1) Nov 11 21:00:28 volumio3beta-hb-xlr-02 autossh[1181]: ssh child pid is 1184 Nov 11 21:00:28 volumio3beta-hb-xlr-02 volumio[674]: info: Remote SSH Started Nov 11 21:00:28 volumio3beta-hb-xlr-02 volumio[674]: verbose: New Socket.io Connection from localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 8 Nov 11 21:00:28 volumio3beta-hb-xlr-02 volumiossh-tunnel[1180]: Warning: Permanently added '[eu2.myvolumio.org]:2222,[185.14.187.123]:2222' (RSA) to the list of known hosts. Nov 11 21:00:28 volumio3beta-hb-xlr-02 volumio[674]: info: CoreCommandRouter::volumioGetState Nov 11 21:01:04 volumio3beta-hb-xlr-02 volumio[674]: info: CALLMETHOD: system_controller system sendBugReport [object Object] Nov 11 21:01:04 volumio3beta-hb-xlr-02 volumio[674]: info: CoreCommandRouter::executeOnPlugin: system , sendBugReport Nov 11 21:01:05 volumio3beta-hb-xlr-02 sudo[1205]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig Nov 11 21:01:05 volumio3beta-hb-xlr-02 sudo[1205]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 11 21:01:05 volumio3beta-hb-xlr-02 sudo[1205]: pam_unix(sudo:session): session closed for user root Nov 11 21:01:13 volumio3beta-hb-xlr-02 sudo[1240]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl -p 7 Nov 11 21:01:13 volumio3beta-hb-xlr-02 sudo[1240]: pam_unix(sudo:session): session opened for user root by (uid=0)