Sat Dec 11 10:58:56 UTC 2021 Description="'rebooted. One click volume steps set to 1 (does not change slider behaviour)'" # 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="543f2de901f774399af6eae9d76f06fe4162e5dd" VOLUMIO_FE_VERSION="dff19baf69ee85af3381c73e932cb6852dfe38e0" VOLUMIO_FE3_VERSION="081f1f7a36b167d793d831ee89ac02e7f8f3ee4b" VOLUMIO_BE_VERSION="1eeee4d17c356c3967f07f80a7dfdbc12de2e066" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 08 Dec 2021 12:21:52 PM CET" VOLUMIO_VERSION="3.173" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="1c9433a7294bad3fb7682386561b54d4" # ifconfig --------------- eth0: flags=4099 mtu 1500 ether b8:27:eb:3c:2a:53 txqueuelen 1000 (Ethernet) RX packets 0 bytes 0 (0.0 B) RX errors 0 dropped 0 overruns 0 frame 0 TX packets 0 bytes 0 (0.0 B) TX errors 0 dropped 0 overruns 0 carrier 0 collisions 0 lo: flags=73 mtu 65536 inet 127.0.0.1 netmask 255.0.0.0 loop txqueuelen 1000 (Local Loopback) RX packets 1132 bytes 124198 (121.2 KiB) RX errors 0 dropped 0 overruns 0 frame 0 TX packets 1132 bytes 124198 (121.2 KiB) TX errors 0 dropped 0 overruns 0 carrier 0 collisions 0 wlan0: flags=4163 mtu 1500 inet 192.168.1.65 netmask 255.255.255.0 broadcast 192.168.1.255 ether 34:0a:33:32:eb:88 txqueuelen 1000 (Ethernet) RX packets 1173 bytes 458710 (447.9 KiB) RX errors 0 dropped 0 overruns 0 frame 0 TX packets 1201 bytes 245237 (239.4 KiB) TX errors 0 dropped 0 overruns 0 carrier 0 collisions 0 # sudo iwconfig --------------- eth0 no wireless extensions. lo no wireless extensions. wlan0 IEEE 802.11gn ESSID:"icanhearyouhavingsex" Nickname:"" Mode:Managed Frequency:2.462 GHz Access Point: 60:E3:27:4F:C2:5D Bit Rate:144.4 Mb/s Sensitivity:0/0 Retry:off RTS thr:off Fragment thr:off Encryption key:****-****-****-****-****-****-****-**** Security mode:open Power Management:off Link Quality=79/100 Signal level=40/100 Noise level=0/100 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: IQaudIODAC [IQaudIODAC], device 0: IQaudIO DAC HiFi pcm512x-hifi-0 [IQaudIO DAC HiFi pcm512x-hifi-0] Subdevices: 1/1 Subdevice #0: subdevice #0 # ps -ef --------------- UID PID PPID C STIME TTY TIME CMD root 1 0 9 10:57 ? 00:00:10 /sbin/init root 2 0 0 10:57 ? 00:00:00 [kthreadd] root 3 2 0 10:57 ? 00:00:00 [rcu_gp] root 4 2 0 10:57 ? 00:00:00 [rcu_par_gp] root 5 2 0 10:57 ? 00:00:00 [kworker/0:0-cgroup_destroy] root 6 2 0 10:57 ? 00:00:00 [kworker/0:0H-mmc_complete] root 7 2 0 10:57 ? 00:00:00 [kworker/u8:0-events_unbound] root 8 2 0 10:57 ? 00:00:00 [mm_percpu_wq] root 9 2 0 10:57 ? 00:00:00 [ksoftirqd/0] root 10 2 0 10:57 ? 00:00:00 [rcu_sched] root 11 2 0 10:57 ? 00:00:00 [migration/0] root 12 2 0 10:57 ? 00:00:00 [cpuhp/0] root 13 2 0 10:57 ? 00:00:00 [cpuhp/1] root 14 2 0 10:57 ? 00:00:00 [migration/1] root 15 2 0 10:57 ? 00:00:00 [ksoftirqd/1] root 16 2 0 10:57 ? 00:00:00 [kworker/1:0-mm_percpu_wq] root 17 2 0 10:57 ? 00:00:00 [kworker/1:0H-kblockd] root 18 2 0 10:57 ? 00:00:00 [cpuhp/2] root 19 2 0 10:57 ? 00:00:00 [migration/2] root 20 2 0 10:57 ? 00:00:00 [ksoftirqd/2] root 21 2 0 10:57 ? 00:00:00 [kworker/2:0-events] root 22 2 0 10:57 ? 00:00:00 [kworker/2:0H-kblockd] root 23 2 0 10:57 ? 00:00:00 [cpuhp/3] root 24 2 0 10:57 ? 00:00:00 [migration/3] root 25 2 0 10:57 ? 00:00:00 [ksoftirqd/3] root 26 2 0 10:57 ? 00:00:00 [kworker/3:0-mm_percpu_wq] root 27 2 0 10:57 ? 00:00:00 [kworker/3:0H-kblockd] root 28 2 0 10:57 ? 00:00:00 [kdevtmpfs] root 29 2 0 10:57 ? 00:00:00 [netns] root 30 2 0 10:57 ? 00:00:00 [kworker/0:1-mm_percpu_wq] root 31 2 0 10:57 ? 00:00:00 [kworker/1:1-events_power_efficient] root 32 2 0 10:57 ? 00:00:00 [kauditd] root 33 2 0 10:57 ? 00:00:00 [khungtaskd] root 34 2 0 10:57 ? 00:00:00 [oom_reaper] root 35 2 0 10:57 ? 00:00:00 [writeback] root 36 2 0 10:57 ? 00:00:00 [kcompactd0] root 54 2 0 10:57 ? 00:00:00 [kblockd] root 55 2 0 10:57 ? 00:00:00 [blkcg_punt_bio] root 56 2 0 10:57 ? 00:00:00 [watchdogd] root 57 2 0 10:57 ? 00:00:00 [kworker/2:1-events_power_efficient] root 58 2 0 10:57 ? 00:00:00 [rpciod] root 59 2 0 10:57 ? 00:00:00 [kworker/u9:0] root 60 2 0 10:57 ? 00:00:00 [xprtiod] root 61 2 0 10:57 ? 00:00:00 [kswapd0] root 62 2 0 10:57 ? 00:00:00 [nfsiod] root 63 2 0 10:57 ? 00:00:00 [kworker/3:1-events] root 64 2 0 10:57 ? 00:00:00 [iscsi_eh] root 65 2 0 10:57 ? 00:00:00 [dwc_otg] root 66 2 0 10:57 ? 00:00:00 [DWC Notificatio] root 67 2 0 10:57 ? 00:00:00 [kworker/2:2-events_power_efficient] root 68 2 0 10:57 ? 00:00:00 [vchiq-slot/0] root 69 2 0 10:57 ? 00:00:00 [vchiq-recy/0] root 70 2 0 10:57 ? 00:00:00 [vchiq-sync/0] root 71 2 0 10:57 ? 00:00:00 [vchiq-keep/0] root 72 2 0 10:57 ? 00:00:00 [SMIO] root 78 2 0 10:57 ? 00:00:00 [mmc_complete] root 79 2 0 10:57 ? 00:00:00 [kworker/2:1H-kblockd] root 81 2 1 10:57 ? 00:00:01 [kworker/0:1H-kblockd] root 100 2 0 10:57 ? 00:00:00 [kworker/1:2-events_power_efficient] root 103 2 0 10:57 ? 00:00:00 [kworker/3:2-mm_percpu_wq] root 104 2 0 10:57 ? 00:00:00 [kworker/0:2H-kblockd] root 108 2 0 10:57 ? 00:00:00 [kworker/2:2H-kblockd] root 109 2 0 10:57 ? 00:00:00 [jbd2/mmcblk0p2-] root 110 2 0 10:57 ? 00:00:00 [ext4-rsv-conver] root 111 2 0 10:57 ? 00:00:00 [kworker/1:1H-kblockd] root 113 2 0 10:57 ? 00:00:00 [kworker/u8:1-events_unbound] root 119 2 1 10:57 ? 00:00:01 [loop0] root 127 2 0 10:57 ? 00:00:00 [kworker/3:1H-kblockd] root 146 2 0 10:57 ? 00:00:00 [kworker/1:2H] root 147 2 0 10:57 ? 00:00:00 [jbd2/mmcblk0p3-] root 148 2 0 10:57 ? 00:00:00 [ext4-rsv-conver] root 155 2 0 10:57 ? 00:00:00 [ipv6_addrconf] root 156 2 0 10:57 ? 00:00:00 [kworker/3:2H-kblockd] root 173 1 1 10:57 ? 00:00:01 /lib/systemd/systemd-journald root 189 2 0 10:57 ? 00:00:00 [kworker/u8:2-events_unbound] root 196 2 0 10:57 ? 00:00:00 [kworker/0:2-events] root 206 1 1 10:57 ? 00:00:01 /lib/systemd/systemd-udevd root 232 2 0 10:57 ? 00:00:00 [SMIO] root 243 2 0 10:57 ? 00:00:00 [mmal-vchiq] root 248 2 0 10:57 ? 00:00:00 [mmal-vchiq] root 249 2 0 10:57 ? 00:00:00 [mmal-vchiq] root 252 2 0 10:57 ? 00:00:00 [mmal-vchiq] root 293 2 0 10:57 ? 00:00:00 [cfg80211] root 294 2 0 10:57 ? 00:00:00 [kworker/2:3] _rpc 358 1 0 10:57 ? 00:00:00 /sbin/rpcbind -f -w root 372 1 2 10:57 ? 00:00:02 /usr/sbin/haveged --Foreground --verbose=1 -w 1024 nobody 393 1 0 10:57 ? 00:00:00 /usr/sbin/thd --triggers /etc/triggerhappy/triggers.d/ --socket /run/thd.socket --user nobody --deviceglob /dev/input/event* volumio 395 1 0 10:57 ? 00:00:00 /usr/local/bin/volumio-remote-updater root 398 1 0 10:57 ? 00:00:00 /lib/systemd/systemd-logind message+ 399 1 1 10:57 ? 00:00:00 /usr/bin/dbus-daemon --system --address=systemd: --nofork --nopidfile --systemd-activation --syslog-only avahi 402 1 0 10:57 ? 00:00:00 avahi-daemon: running [volumiokhv.local] root 403 1 0 10:57 ? 00:00:00 /usr/sbin/alsactl -E HOME=/run/alsa -s -n 19 -c rdaemon root 407 1 0 10:57 ? 00:00:00 /sbin/wpa_supplicant -u -s -O /run/wpa_supplicant avahi 419 402 0 10:57 ? 00:00:00 avahi-daemon: chroot helper root 421 2 0 10:57 ? 00:00:00 [RTW_CMD_THREAD] shairpo+ 517 1 0 10:57 ? 00:00:00 /usr/bin/shairport-sync --configfile=/tmp/shairport-sync.conf root 526 1 1 10:57 ? 00:00:00 /usr/sbin/nmbd --foreground --no-process-group root 545 1 0 10:57 ? 00:00:00 /usr/sbin/ifplugd -i eth0 -q -f -u0 -d10 -w -I ntp 546 1 0 10:57 ? 00:00:00 /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 103:103 root 552 1 0 10:57 ? 00:00:00 /sbin/agetty -o -p -- \u --keep-baud 115200,38400,9600 ttyAMA0 vt220 root 553 1 0 10:57 tty1 00:00:00 /sbin/agetty -o -p -- \u --noclear tty1 linux root 568 526 0 10:57 ? 00:00:00 /usr/sbin/nmbd --foreground --no-process-group root 608 1 0 10:57 ? 00:00:00 wpa_supplicant -s -B -Dnl80211,wext -c/etc/wpa_supplicant/wpa_supplicant.conf -iwlan0 root 625 1 0 10:57 ? 00:00:00 /sbin/dhcpcd volumio 691 1 48 10:57 ? 00:00:37 /usr/bin/node /volumio/index.js root 704 1 0 10:57 ? 00:00:00 /usr/sbin/winbindd --foreground --no-process-group root 707 704 0 10:57 ? 00:00:00 winbindd: domain child [VOLUMIOKHV] root 709 1 1 10:57 ? 00:00:00 /usr/sbin/smbd --foreground --no-process-group root 711 709 0 10:57 ? 00:00:00 /usr/sbin/smbd --foreground --no-process-group root 712 709 0 10:57 ? 00:00:00 /usr/sbin/smbd --foreground --no-process-group root 713 704 0 10:57 ? 00:00:00 winbindd: idmap child root 714 704 0 10:57 ? 00:00:00 winbindd: domain child [BUILTIN] root 716 709 0 10:57 ? 00:00:00 /usr/sbin/smbd --foreground --no-process-group root 770 2 0 10:57 ? 00:00:00 [kworker/u8:3-events_unbound] root 772 2 0 10:57 ? 00:00:00 [cifsiod] root 773 2 0 10:57 ? 00:00:00 [smb3decryptd] root 774 2 0 10:57 ? 00:00:00 [cifsfileinfoput] root 775 2 0 10:57 ? 00:00:00 [cifsoplockd] root 776 2 0 10:57 ? 00:00:00 [kworker/u8:4] root 780 2 0 10:57 ? 00:00:00 [cifsd] root 781 2 0 10:57 ? 00:00:00 [cifsd] root 782 2 0 10:57 ? 00:00:00 [cifsd] volumio 822 691 1 10:57 ? 00:00:01 /usr/bin/node /volumio/app/plugins/miscellanea/albumart/serverStartup.js 3001 /data/albumart volumio 829 822 5 10:57 ? 00:00:03 /usr/bin/node /volumio/app/plugins/miscellanea/albumart/serverStartup.js 3001 /data/albumart volumio 830 822 6 10:57 ? 00:00:03 /usr/bin/node /volumio/app/plugins/miscellanea/albumart/serverStartup.js 3001 /data/albumart volumio 831 822 6 10:57 ? 00:00:03 /usr/bin/node /volumio/app/plugins/miscellanea/albumart/serverStartup.js 3001 /data/albumart volumio 910 1 1 10:58 ? 00:00:00 /usr/bin/spopd -f -c /etc/spopd.conf volumio 913 1 0 10:58 ? 00:00:00 /bin/bash /data/plugins/music_service/volspotconnect2/startconnect.sh volumio 915 913 0 10:58 ? 00:00:00 ./vollibrespot -c volspotify.toml mpd 920 1 5 10:58 ? 00:00:02 /usr/bin/mpd --no-daemon volumio 936 1 1 10:58 ? 00:00:00 /bin/streaming-daemon volumio 959 1 0 10:58 ? 00:00:00 /bin/sh /tmp/sshtunnel.sh volumio 960 959 0 10:58 ? 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 fzPHMW7dd6SAQ6y39R7zUbaFSXH3-adeb3a6efac73a226f4fe35c1a78be0a.eu1.myvolumio.org:8091:localhost:3000 eu1.myvolumio.org volumio 963 960 0 10:58 ? 00:00:00 /usr/bin/ssh -o ServerAliveInterval 30 -o ServerAliveCountMax 3 -o StrictHostKeyChecking=no -o UserKnownHostsFile=/dev/null -N -p 2222 -R fzPHMW7dd6SAQ6y39R7zUbaFSXH3-adeb3a6efac73a226f4fe35c1a78be0a.eu1.myvolumio.org:8091:localhost:3000 eu1.myvolumio.org volumio 991 1 0 10:58 ? 00:00:00 /bin/sh /bin/bt/btstart.sh start volumio 1002 1 0 10:58 ? 00:00:00 /bin/sh -c /usr/bin/vtcs volumio 1003 1002 3 10:58 ? 00:00:00 /usr/bin/vtcs volumio 1024 1 1 10:58 ? 00:00:00 /usr/bin/pulseaudio rtkit 1025 1 0 10:58 ? 00:00:00 /usr/lib/rtkit/rtkit-daemon root 1028 1 1 10:58 ? 00:00:00 /usr/lib/policykit-1/polkitd --no-debug root 1033 1 0 10:58 ? 00:00:00 /usr/lib/bluetooth/bluetoothd --noplugin=sap volumio 1038 991 4 10:58 ? 00:00:01 /usr/bin/python /bin/bt/simple-agent -c NoInputNoOutput root 1039 1 1 10:58 ? 00:00:00 /lib/systemd/systemd-hostnamed volumio 1045 691 1 10:58 ? 00:00:00 /bin/sh -c /usr/bin/node /volumio/logsubmit.js 'rebooted. One click volume steps set to 1 (does not change slider behaviour)' volumio 1046 1045 73 10:58 ? 00:00:00 /usr/bin/node /volumio/logsubmit.js rebooted. One click volume steps set to 1 (does not change slider behaviour) volumio 1066 1046 0 10:58 ? 00:00:00 /bin/sh -c ps -ef >>/tmp/logondemand 2>&1 volumio 1067 1066 0 10:58 ? 00:00:00 ps -ef # df -h --------------- Filesystem Size Used Avail Use% Mounted on /dev/mmcblk0p2 2.5G 434M 1.9G 19% /imgpart /dev/loop0 375M 375M 0 100% /static overlay 56G 241M 53G 1% / devtmpfs 446M 0 446M 0% /dev tmpfs 485M 12K 485M 1% /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 20M 28K 20M 1% /var/log tmpfs 485M 0 485M 0% /var/spool/cups tmpfs 485M 44K 485M 1% /tmp tmpfs 485M 0 485M 0% /var/spool/cups/tmp /dev/mmcblk0p1 92M 52M 41M 56% /boot //192.168.1.15/musicshare_hires 1.8T 1.3T 580G 69% /mnt/NAS/Musik_HiRes //192.168.1.15/musicshare 1.8T 1.3T 580G 69% /mnt/NAS/Musik //192.168.1.15/musicshare_new 1.8T 1.3T 580G 69% /mnt/NAS/Musik_Neu # 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=456276k,nr_inodes=114069,mode=755) securityfs on /sys/kernel/security type securityfs (rw,nosuid,nodev,noexec,relatime) tmpfs on /dev/shm type tmpfs (rw,nosuid,nodev,noexec) devpts on /dev/pts type devpts (rw,nosuid,noexec,relatime,gid=5,mode=620,ptmxmode=000) tmpfs on /run type tmpfs (rw,nosuid,nodev,mode=755) tmpfs on /run/lock type tmpfs (rw,nosuid,nodev,noexec,relatime,size=5120k) tmpfs on /sys/fs/cgroup type tmpfs (ro,nosuid,nodev,noexec,mode=755) cgroup2 on /sys/fs/cgroup/unified type cgroup2 (rw,nosuid,nodev,noexec,relatime,nsdelegate) cgroup on /sys/fs/cgroup/systemd type cgroup (rw,nosuid,nodev,noexec,relatime,xattr,name=systemd) none on /sys/fs/bpf type bpf (rw,nosuid,nodev,noexec,relatime,mode=700) cgroup on /sys/fs/cgroup/blkio type cgroup (rw,nosuid,nodev,noexec,relatime,blkio) cgroup on /sys/fs/cgroup/cpu,cpuacct type cgroup (rw,nosuid,nodev,noexec,relatime,cpu,cpuacct) cgroup on /sys/fs/cgroup/freezer type cgroup (rw,nosuid,nodev,noexec,relatime,freezer) 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/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/perf_event type cgroup (rw,nosuid,nodev,noexec,relatime,perf_event) systemd-1 on /proc/sys/fs/binfmt_misc type autofs (rw,relatime,fd=31,pgrp=1,timeout=0,minproto=5,maxproto=5,direct) 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) configfs on /sys/kernel/config type configfs (rw,relatime) tmpfs on /var/log type tmpfs (rw,nodev,relatime,size=20480k,mode=777,uid=1000,gid=4) tmpfs on /var/spool/cups type tmpfs (rw,noatime,mode=755) tmpfs on /tmp type tmpfs (rw,noatime,mode=755) tmpfs on /var/spool/cups/tmp type tmpfs (rw,noatime,mode=755) /dev/mmcblk0p1 on /boot type vfat (rw,nosuid,nodev,noexec,relatime,fmask=0111,dmask=0000,allow_utime=0022,codepage=437,iocharset=ascii,shortname=mixed,utf8,errors=remount-ro,user) //192.168.1.15/musicshare_hires on /mnt/NAS/Musik_HiRes type cifs (ro,relatime,vers=3.1.1,cache=strict,username=musicshare,uid=0,noforceuid,gid=0,noforcegid,addr=192.168.1.15,file_mode=0666,dir_mode=0777,soft,nounix,serverino,mapposix,rsize=4194304,wsize=4194304,bsize=1048576,echo_interval=60,actimeo=1) //192.168.1.15/musicshare on /mnt/NAS/Musik type cifs (ro,relatime,vers=3.1.1,cache=strict,username=musicshare,uid=0,noforceuid,gid=0,noforcegid,addr=192.168.1.15,file_mode=0666,dir_mode=0777,soft,nounix,serverino,mapposix,rsize=4194304,wsize=4194304,bsize=1048576,echo_interval=60,actimeo=1) //192.168.1.15/musicshare_new on /mnt/NAS/Musik_Neu type cifs (ro,relatime,vers=3.1.1,cache=strict,username=musicshare,uid=0,noforceuid,gid=0,noforcegid,addr=192.168.1.15,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 1024/pulseaudio tcp 0 0 127.0.0.1:6602 0.0.0.0:* LISTEN 910/spopd 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:34033 0.0.0.0:* LISTEN 915/./vollibrespot tcp 0 0 0.0.0.0:445 0.0.0.0:* LISTEN - tcp 0 0 127.0.0.1:56756 127.0.0.1:3000 TIME_WAIT - tcp 0 0 192.168.1.65:36888 188.166.162.121:2222 ESTABLISHED 963/ssh tcp 0 0 192.168.1.65:34318 192.168.1.15:445 ESTABLISHED - tcp 0 0 192.168.1.65:37696 84.247.14.230:80 ESTABLISHED 691/node tcp 0 0 127.0.0.1:6602 127.0.0.1:56726 ESTABLISHED 910/spopd tcp 0 0 192.168.1.65:42700 172.67.141.32:443 ESTABLISHED 936/streaming-daemo tcp 0 0 127.0.0.1:56728 127.0.0.1:6602 ESTABLISHED 691/node tcp 0 0 127.0.0.1:56754 127.0.0.1:3000 TIME_WAIT - tcp 0 0 127.0.0.1:6602 127.0.0.1:56728 ESTABLISHED 910/spopd tcp 0 0 192.168.1.65:34322 192.168.1.15:445 ESTABLISHED - tcp 0 0 192.168.1.65:53826 192.168.1.192:3000 ESTABLISHED 691/node tcp 0 0 192.168.1.65:42798 172.67.141.32:443 ESTABLISHED 936/streaming-daemo tcp 0 0 127.0.0.1:56586 127.0.0.1:3000 ESTABLISHED 691/node tcp 0 0 127.0.0.1:56726 127.0.0.1:6602 ESTABLISHED 691/node tcp 0 0 192.168.1.65:48306 149.210.234.235:80 TIME_WAIT - tcp 0 0 192.168.1.65:36276 35.201.97.85:443 ESTABLISHED 691/node tcp 0 0 192.168.1.65:34316 192.168.1.15:445 ESTABLISHED - tcp 0 0 192.168.1.65:42796 172.67.141.32:443 ESTABLISHED 936/streaming-daemo tcp 0 0 192.168.1.65:44556 35.190.242.24:4070 ESTABLISHED 910/spopd tcp 0 0 127.0.0.1:56528 127.0.0.1:3000 ESTABLISHED 691/node tcp 0 0 192.168.1.65:42696 172.67.141.32:443 ESTABLISHED 936/streaming-daemo tcp 0 0 127.0.0.1:56506 127.0.0.1:3000 ESTABLISHED 395/volumio-remote- tcp6 0 0 :::5000 :::* LISTEN - tcp6 0 0 :::6600 :::* LISTEN - tcp6 0 0 :::4713 :::* LISTEN 1024/pulseaudio tcp6 0 0 :::139 :::* LISTEN - tcp6 0 0 :::111 :::* LISTEN - tcp6 0 0 :::3000 :::* LISTEN 691/node tcp6 0 0 :::3001 :::* LISTEN 822/node tcp6 0 0 :::3005 :::* LISTEN 691/node tcp6 0 0 :::445 :::* LISTEN - tcp6 0 0 :::7777 :::* LISTEN 936/streaming-daemo tcp6 0 0 :::2019 :::* LISTEN 1003/vtcs tcp6 0 0 :::4004 :::* LISTEN 691/node tcp6 0 0 127.0.0.1:7777 127.0.0.1:49498 TIME_WAIT - tcp6 0 0 127.0.0.1:7777 127.0.0.1:49532 TIME_WAIT - tcp6 0 0 127.0.0.1:7777 127.0.0.1:49592 TIME_WAIT - tcp6 0 0 127.0.0.1:7777 127.0.0.1:49552 TIME_WAIT - tcp6 0 0 127.0.0.1:7777 127.0.0.1:49590 TIME_WAIT - tcp6 0 0 127.0.0.1:3000 127.0.0.1:56600 TIME_WAIT - tcp6 0 0 127.0.0.1:7777 127.0.0.1:49456 TIME_WAIT - tcp6 0 0 127.0.0.1:7777 127.0.0.1:49570 TIME_WAIT - tcp6 0 0 127.0.0.1:7777 127.0.0.1:49432 TIME_WAIT - tcp6 0 0 127.0.0.1:7777 127.0.0.1:49478 TIME_WAIT - tcp6 0 0 192.168.1.65:3000 192.168.1.234:42088 TIME_WAIT - tcp6 0 0 127.0.0.1:7777 127.0.0.1:49442 TIME_WAIT - tcp6 0 0 127.0.0.1:7777 127.0.0.1:49594 TIME_WAIT - tcp6 0 0 127.0.0.1:7777 127.0.0.1:49584 TIME_WAIT - tcp6 0 0 127.0.0.1:7777 127.0.0.1:49544 TIME_WAIT - tcp6 0 0 192.168.1.65:3000 192.168.1.234:42098 ESTABLISHED 691/node tcp6 0 0 127.0.0.1:7777 127.0.0.1:49566 TIME_WAIT - tcp6 0 0 127.0.0.1:7777 127.0.0.1:49504 TIME_WAIT - tcp6 0 0 127.0.0.1:7777 127.0.0.1:49520 TIME_WAIT - tcp6 0 0 127.0.0.1:3000 127.0.0.1:56514 TIME_WAIT - tcp6 0 0 127.0.0.1:7777 127.0.0.1:49414 TIME_WAIT - tcp6 0 0 127.0.0.1:7777 127.0.0.1:49454 TIME_WAIT - tcp6 0 0 127.0.0.1:7777 127.0.0.1:49568 TIME_WAIT - tcp6 0 0 127.0.0.1:7777 127.0.0.1:49596 TIME_WAIT - tcp6 0 0 127.0.0.1:7777 127.0.0.1:49452 TIME_WAIT - tcp6 0 0 127.0.0.1:7777 127.0.0.1:49528 TIME_WAIT - tcp6 0 0 127.0.0.1:7777 127.0.0.1:49534 TIME_WAIT - tcp6 0 0 127.0.0.1:7777 127.0.0.1:49482 TIME_WAIT - tcp6 0 0 127.0.0.1:7777 127.0.0.1:49492 TIME_WAIT - tcp6 0 0 127.0.0.1:7777 127.0.0.1:49564 TIME_WAIT - tcp6 0 0 127.0.0.1:7777 127.0.0.1:49462 TIME_WAIT - tcp6 0 0 127.0.0.1:7777 127.0.0.1:49486 TIME_WAIT - tcp6 0 0 192.168.1.65:3000 192.168.1.234:59022 TIME_WAIT - tcp6 0 0 192.168.1.65:3000 192.168.1.192:38612 ESTABLISHED 691/node tcp6 0 0 127.0.0.1:7777 127.0.0.1:49460 TIME_WAIT - tcp6 0 0 127.0.0.1:3000 127.0.0.1:56532 TIME_WAIT - tcp6 0 0 127.0.0.1:7777 127.0.0.1:49550 TIME_WAIT - tcp6 0 0 127.0.0.1:7777 127.0.0.1:49444 TIME_WAIT - tcp6 0 0 192.168.1.65:3000 192.168.1.234:59044 ESTABLISHED 691/node tcp6 0 0 127.0.0.1:7777 127.0.0.1:49540 TIME_WAIT - tcp6 0 0 127.0.0.1:7777 127.0.0.1:49548 TIME_WAIT - tcp6 0 0 192.168.1.65:3000 192.168.1.234:42094 TIME_WAIT - tcp6 0 0 127.0.0.1:7777 127.0.0.1:49476 TIME_WAIT - tcp6 0 0 127.0.0.1:7777 127.0.0.1:49554 TIME_WAIT - tcp6 0 0 127.0.0.1:7777 127.0.0.1:49574 TIME_WAIT - tcp6 0 0 127.0.0.1:7777 127.0.0.1:49518 TIME_WAIT - tcp6 0 0 127.0.0.1:3000 127.0.0.1:56588 TIME_WAIT - tcp6 0 0 127.0.0.1:7777 127.0.0.1:49538 TIME_WAIT - tcp6 0 0 127.0.0.1:3000 127.0.0.1:56530 TIME_WAIT - tcp6 0 0 192.168.1.65:3000 192.168.1.234:59042 FIN_WAIT2 - tcp6 0 0 192.168.1.65:3000 192.168.1.234:59036 ESTABLISHED 691/node tcp6 0 0 127.0.0.1:7777 127.0.0.1:49536 TIME_WAIT - tcp6 0 0 127.0.0.1:7777 127.0.0.1:49546 TIME_WAIT - tcp6 0 0 127.0.0.1:7777 127.0.0.1:49466 TIME_WAIT - tcp6 0 0 127.0.0.1:7777 127.0.0.1:49472 TIME_WAIT - tcp6 0 0 127.0.0.1:7777 127.0.0.1:49470 TIME_WAIT - tcp6 0 0 127.0.0.1:7777 127.0.0.1:49488 TIME_WAIT - tcp6 0 0 192.168.1.65:3000 192.168.1.234:59028 TIME_WAIT - tcp6 0 0 192.168.1.65:3000 192.168.1.234:42092 ESTABLISHED 691/node tcp6 0 0 127.0.0.1:7777 127.0.0.1:49502 TIME_WAIT - tcp6 0 0 127.0.0.1:7777 127.0.0.1:49572 TIME_WAIT - tcp6 0 0 127.0.0.1:7777 127.0.0.1:49464 TIME_WAIT - tcp6 0 0 127.0.0.1:7777 127.0.0.1:49558 TIME_WAIT - tcp6 0 0 127.0.0.1:3000 127.0.0.1:56586 ESTABLISHED 691/node tcp6 0 0 127.0.0.1:7777 127.0.0.1:49526 TIME_WAIT - tcp6 0 0 127.0.0.1:7777 127.0.0.1:49420 TIME_WAIT - tcp6 0 0 127.0.0.1:7777 127.0.0.1:49506 TIME_WAIT - tcp6 0 0 127.0.0.1:3000 127.0.0.1:56528 ESTABLISHED 691/node tcp6 0 0 127.0.0.1:7777 127.0.0.1:49484 TIME_WAIT - tcp6 0 0 192.168.1.65:3000 192.168.1.234:59032 FIN_WAIT2 - tcp6 0 0 127.0.0.1:3000 127.0.0.1:56506 ESTABLISHED 691/node tcp6 0 0 127.0.0.1:7777 127.0.0.1:49542 TIME_WAIT - tcp6 0 0 127.0.0.1:7777 127.0.0.1:49530 TIME_WAIT - tcp6 0 0 127.0.0.1:7777 127.0.0.1:49588 TIME_WAIT - tcp6 0 0 127.0.0.1:7777 127.0.0.1:49586 TIME_WAIT - tcp6 0 0 127.0.0.1:7777 127.0.0.1:49560 TIME_WAIT - tcp6 0 0 127.0.0.1:7777 127.0.0.1:49480 TIME_WAIT - tcp6 0 0 127.0.0.1:7777 127.0.0.1:49556 TIME_WAIT - tcp6 0 0 127.0.0.1:7777 127.0.0.1:49500 TIME_WAIT - tcp6 0 0 127.0.0.1:7777 127.0.0.1:49474 TIME_WAIT - tcp6 0 0 127.0.0.1:3000 127.0.0.1:56534 TIME_WAIT - tcp6 0 0 127.0.0.1:7777 127.0.0.1:49494 TIME_WAIT - tcp6 0 0 192.168.1.65:3000 192.168.1.234:59030 TIME_WAIT - tcp6 0 0 127.0.0.1:7777 127.0.0.1:49440 TIME_WAIT - tcp6 0 0 127.0.0.1:7777 127.0.0.1:49582 TIME_WAIT - tcp6 0 0 192.168.1.65:3000 192.168.1.234:42096 TIME_WAIT - tcp6 0 0 192.168.1.65:3000 192.168.1.234:59026 TIME_WAIT - tcp6 0 0 127.0.0.1:7777 127.0.0.1:49468 TIME_WAIT - tcp6 0 0 192.168.1.65:3000 192.168.1.234:59034 FIN_WAIT2 - tcp6 0 0 192.168.1.65:3000 192.168.1.234:59040 ESTABLISHED 691/node tcp6 0 0 127.0.0.1:7777 127.0.0.1:49510 TIME_WAIT - tcp6 0 0 127.0.0.1:7777 127.0.0.1:49576 TIME_WAIT - tcp6 0 0 127.0.0.1:7777 127.0.0.1:49578 TIME_WAIT - tcp6 0 0 127.0.0.1:3000 127.0.0.1:56568 TIME_WAIT - tcp6 0 0 127.0.0.1:7777 127.0.0.1:49508 TIME_WAIT - tcp6 0 0 127.0.0.1:7777 127.0.0.1:49580 TIME_WAIT - tcp6 0 0 127.0.0.1:7777 127.0.0.1:49496 TIME_WAIT - tcp6 0 0 127.0.0.1:7777 127.0.0.1:49562 TIME_WAIT - tcp6 0 0 127.0.0.1:7777 127.0.0.1:49490 TIME_WAIT - tcp6 0 0 127.0.0.1:7777 127.0.0.1:49512 TIME_WAIT - tcp6 0 0 127.0.0.1:3000 127.0.0.1:56598 TIME_WAIT - tcp6 0 0 127.0.0.1:7777 127.0.0.1:49458 TIME_WAIT - tcp6 0 0 127.0.0.1:7777 127.0.0.1:49450 TIME_WAIT - # cat /etc/asound.conf --------------- pcm.!default { type empty slave.pcm "volumio" } pcm.volumio { type empty slave.pcm "volumioOutput" } # There is always a plug before the hardware to be safe pcm.volumioOutput { type plug slave.pcm "volumioHw" } pcm.volumioHw { type hw card "IQaudIODAC" } # cat /var/log/mpd.log --------------- Dec 11 10:57 : zeroconf: No global port, disabling zeroconf Dec 11 10:57 : zeroconf: No global port, disabling zeroconf Dec 11 10:57 : client: [0] opened from local Dec 11 10:57 : exception: No such directory Dec 11 10:57 : client: [1] opened from local Dec 11 10:57 : zeroconf: No global port, disabling zeroconf Dec 11 10:57 : client: [0] opened from local Dec 11 10:57 : client: [1] opened from local # volumio plugin list --------------- This command will list installed plugins on your device ----------------------- Installed Plugins: [ { prettyName: 'Spotify', name: 'spop', category: 'music_service', version: '2.0.3', icon: 'fa-spotify', isManuallyInstalled: false, enabled: true, active: true }, { prettyName: 'Volumio Spotify Connect2', name: 'volspotconnect2', category: 'music_service', version: '3.0.7', icon: 'fa-spotify', isManuallyInstalled: false, enabled: true, active: true }, { prettyName: 'System information', name: 'Systeminfo', category: 'user_interface', version: '3.0.0', icon: 'fa-info-circle', isManuallyInstalled: false, enabled: true, active: true }, { prettyName: 'Now Playing', name: 'now_playing', category: 'user_interface', version: '0.1.3', icon: 'fa-play-circle', isManuallyInstalled: false, enabled: true, active: true } ] ------------------------------------------ # cat /data/configuration/plugins.json --------------- { "music_service": { "airplay_emulation": { "enabled": { "type": "boolean", "value": false }, "status": { "type": "string", "value": "STOPPED" } }, "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": false }, "status": { "type": "string", "value": "STOPPED" } }, "streaming_services": { "status": { "type": "string", "value": "STARTING" } }, "cd_controller": { "status": { "type": "string", "value": "STOPPED" }, "enabled": { "type": "boolean", "value": false } }, "tidal": { "status": { "type": "string", "value": "STARTED" } }, "qobuz": { "status": { "type": "string", "value": "STARTED" } }, "tidalconnect": { "status": { "type": "string", "value": "STARTED" } }, "spop": { "enabled": { "type": "boolean", "value": true }, "status": { "type": "string", "value": "STARTED" } }, "volspotconnect2": { "enabled": { "type": "boolean", "value": true }, "status": { "type": "string", "value": "STARTED" } } }, "audio_interface": { "outputs": { "enabled": { "type": "boolean", "value": true }, "status": { "type": "string", "value": "STARTED" } }, "upnp": { "enabled": { "type": "boolean", "value": false }, "status": { "type": "string", "value": "STOPPED" } }, "alsa_controller": { "enabled": { "type": "boolean", "value": true }, "status": { "type": "string", "value": "STARTED" } }, "bluetooth": { "status": { "type": "string", "value": "STARTED" }, "enabled": { "type": "boolean", "value": true } } }, "miscellanea": { "alarm-clock": { "enabled": { "type": "boolean", "value": true }, "status": { "type": "string", "value": "STARTED" } }, "albumart": { "enabled": { "type": "boolean", "value": true }, "status": { "type": "string", "value": "STARTED" } }, "appearance": { "enabled": { "type": "boolean", "value": true }, "status": { "type": "string", "value": "STARTED" } }, "wizard": { "enabled": { "type": "boolean", "value": true }, "status": { "type": "string", "value": "STARTED" } }, "my_music": { "enabled": { "type": "boolean", "value": true }, "status": { "type": "string", "value": "STARTED" } } }, "user_interface": { "websocket": { "enabled": { "type": "boolean", "value": true }, "status": { "type": "string", "value": "STARTED" } }, "mpdemulation": { "enabled": { "type": "boolean", "value": false }, "status": { "type": "string", "value": "STARTED" } }, "rest_api": { "enabled": { "type": "boolean", "value": true }, "status": { "type": "string", "value": "STARTED" } }, "Systeminfo": { "enabled": { "type": "boolean", "value": true }, "status": { "type": "string", "value": "STARTED" } }, "now_playing": { "enabled": { "type": "boolean", "value": true }, "status": { "type": "string", "value": "STARTED" } } }, "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" mixer_device "hw:2,0" mixer_control "Digital" mixer_type "hardware" } 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 "4096" ############################################################################### # 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, 969 ms: OK https://www.googleapis.com, 687 ms: OK https://securetoken.googleapis.com, 756 ms: OK https://myvolumio.firebaseio.com, 999 ms: OK https://functions.volumio.cloud, 1041 ms: OK https://oauth-performer.dfs.volumio.org, 688 ms: OK https://browsing-performer.dfs.volumio.org, 679 ms: OK http://cddb.volumio.org, 4807 ms: OK https://functions.volumio.cloud, 1330 ms: OK http://pushupdates.volumio.org, 473 ms: OK http://plugins.volumio.org, 4781 ms: OK https://database.volumio.cloud, 787 ms: OK https://radio-directory.firebaseapp.com, 650 ms: OK ---------- REMOTE ENDPOINTS TEST OK, all Endpoints are reachable ---------- # sudo journalctl -p 7 --------------- -- Logs begin at Thu 2019-02-14 10:11:59 UTC, end at Sat 2021-12-11 10:59:09 UTC. -- Feb 14 10:11:59 volumiokhv kernel: Booting Linux on physical CPU 0xf00 Feb 14 10:11:59 volumiokhv kernel: Linux version 5.4.83-v7+ (dom@buildbot) (gcc version 8.4.0 (Ubuntu/Linaro 8.4.0-3ubuntu1)) #1379 SMP Mon Dec 14 13:08:57 GMT 2020 Feb 14 10:11:59 volumiokhv kernel: CPU: ARMv7 Processor [410fc075] revision 5 (ARMv7), cr=10c5387d Feb 14 10:11:59 volumiokhv kernel: CPU: div instructions available: patching division code Feb 14 10:11:59 volumiokhv kernel: CPU: PIPT / VIPT nonaliasing data cache, VIPT aliasing instruction cache Feb 14 10:11:59 volumiokhv kernel: OF: fdt: Machine model: Raspberry Pi 2 Model B Rev 1.1 Feb 14 10:11:59 volumiokhv kernel: Memory policy: Data cache writealloc Feb 14 10:11:59 volumiokhv kernel: Reserved memory: created CMA memory pool at 0x3a000000, size 64 MiB Feb 14 10:11:59 volumiokhv kernel: OF: reserved mem: initialized node linux,cma, compatible id shared-dma-pool Feb 14 10:11:59 volumiokhv kernel: On node 0 totalpages: 253952 Feb 14 10:11:59 volumiokhv kernel: Normal zone: 2232 pages used for memmap Feb 14 10:11:59 volumiokhv kernel: Normal zone: 0 pages reserved Feb 14 10:11:59 volumiokhv kernel: Normal zone: 253952 pages, LIFO batch:63 Feb 14 10:11:59 volumiokhv kernel: percpu: Embedded 20 pages/cpu s49740 r8192 d23988 u81920 Feb 14 10:11:59 volumiokhv kernel: pcpu-alloc: s49740 r8192 d23988 u81920 alloc=20*4096 Feb 14 10:11:59 volumiokhv kernel: pcpu-alloc: [0] 0 [0] 1 [0] 2 [0] 3 Feb 14 10:11:59 volumiokhv kernel: Built 1 zonelists, mobility grouping on. Total pages: 251720 Feb 14 10:11:59 volumiokhv 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:11:59 volumiokhv kernel: Dentry cache hash table entries: 131072 (order: 7, 524288 bytes, linear) Feb 14 10:11:59 volumiokhv kernel: Inode-cache hash table entries: 65536 (order: 6, 262144 bytes, linear) Feb 14 10:11:59 volumiokhv kernel: mem auto-init: stack:off, heap alloc:off, heap free:off Feb 14 10:11:59 volumiokhv kernel: Memory: 912552K/1015808K available (9216K kernel code, 698K rwdata, 2608K rodata, 1024K init, 827K bss, 37720K reserved, 65536K cma-reserved) Feb 14 10:11:59 volumiokhv kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 Feb 14 10:11:59 volumiokhv kernel: ftrace: allocating 29205 entries in 58 pages Feb 14 10:11:59 volumiokhv kernel: rcu: Hierarchical RCU implementation. Feb 14 10:11:59 volumiokhv kernel: rcu: RCU calculated value of scheduler-enlistment delay is 10 jiffies. Feb 14 10:11:59 volumiokhv kernel: NR_IRQS: 16, nr_irqs: 16, preallocated irqs: 16 Feb 14 10:11:59 volumiokhv kernel: random: get_random_bytes called from start_kernel+0x324/0x4f8 with crng_init=0 Feb 14 10:11:59 volumiokhv kernel: arch_timer: cp15 timer(s) running at 19.20MHz (phys). Feb 14 10:11:59 volumiokhv kernel: clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x46d987e47, max_idle_ns: 440795202767 ns Feb 14 10:11:59 volumiokhv kernel: sched_clock: 56 bits at 19MHz, resolution 52ns, wraps every 4398046511078ns Feb 14 10:11:59 volumiokhv kernel: Switching to timer-based delay loop, resolution 52ns Feb 14 10:11:59 volumiokhv kernel: Console: colour dummy device 80x30 Feb 14 10:11:59 volumiokhv kernel: printk: console [tty1] enabled Feb 14 10:11:59 volumiokhv kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 38.40 BogoMIPS (lpj=192000) Feb 14 10:11:59 volumiokhv kernel: pid_max: default: 32768 minimum: 301 Feb 14 10:11:59 volumiokhv kernel: LSM: Security Framework initializing Feb 14 10:11:59 volumiokhv kernel: Mount-cache hash table entries: 2048 (order: 1, 8192 bytes, linear) Feb 14 10:11:59 volumiokhv kernel: Mountpoint-cache hash table entries: 2048 (order: 1, 8192 bytes, linear) Feb 14 10:11:59 volumiokhv kernel: Disabling memory control group subsystem Feb 14 10:11:59 volumiokhv kernel: CPU: Testing write buffer coherency: ok Feb 14 10:11:59 volumiokhv kernel: CPU0: update cpu_capacity 1024 Feb 14 10:11:59 volumiokhv kernel: CPU0: thread -1, cpu 0, socket 15, mpidr 80000f00 Feb 14 10:11:59 volumiokhv kernel: Setting up static identity map for 0x100000 - 0x10003c Feb 14 10:11:59 volumiokhv kernel: rcu: Hierarchical SRCU implementation. Feb 14 10:11:59 volumiokhv kernel: smp: Bringing up secondary CPUs ... Feb 14 10:11:59 volumiokhv kernel: CPU1: update cpu_capacity 1024 Feb 14 10:11:59 volumiokhv kernel: CPU1: thread -1, cpu 1, socket 15, mpidr 80000f01 Feb 14 10:11:59 volumiokhv kernel: CPU2: update cpu_capacity 1024 Feb 14 10:11:59 volumiokhv kernel: CPU2: thread -1, cpu 2, socket 15, mpidr 80000f02 Feb 14 10:11:59 volumiokhv kernel: CPU3: update cpu_capacity 1024 Feb 14 10:11:59 volumiokhv kernel: CPU3: thread -1, cpu 3, socket 15, mpidr 80000f03 Feb 14 10:11:59 volumiokhv kernel: smp: Brought up 1 node, 4 CPUs Feb 14 10:11:59 volumiokhv kernel: SMP: Total of 4 processors activated (153.60 BogoMIPS). Feb 14 10:11:59 volumiokhv kernel: CPU: All CPU(s) started in HYP mode. Feb 14 10:11:59 volumiokhv kernel: CPU: Virtualization extensions available. Feb 14 10:11:59 volumiokhv kernel: devtmpfs: initialized Feb 14 10:11:59 volumiokhv kernel: VFP support v0.3: implementor 41 architecture 2 part 30 variant 7 rev 5 Feb 14 10:11:59 volumiokhv kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604462750000 ns Feb 14 10:11:59 volumiokhv kernel: futex hash table entries: 1024 (order: 4, 65536 bytes, linear) Feb 14 10:11:59 volumiokhv kernel: pinctrl core: initialized pinctrl subsystem Feb 14 10:11:59 volumiokhv kernel: NET: Registered protocol family 16 Feb 14 10:11:59 volumiokhv kernel: DMA: preallocated 1024 KiB pool for atomic coherent allocations Feb 14 10:11:59 volumiokhv kernel: audit: initializing netlink subsys (disabled) Feb 14 10:11:59 volumiokhv kernel: audit: type=2000 audit(0.050:1): state=initialized audit_enabled=0 res=1 Feb 14 10:11:59 volumiokhv kernel: hw-breakpoint: found 5 (+1 reserved) breakpoint and 4 watchpoint registers. Feb 14 10:11:59 volumiokhv kernel: hw-breakpoint: maximum watchpoint size is 8 bytes. Feb 14 10:11:59 volumiokhv kernel: Serial: AMBA PL011 UART driver Feb 14 10:11:59 volumiokhv kernel: bcm2835-mbox 3f00b880.mailbox: mailbox enabled Feb 14 10:12:00 volumiokhv kernel: raspberrypi-firmware soc:firmware: Attached to firmware from 2021-01-08 14:33, variant start Feb 14 10:12:00 volumiokhv kernel: raspberrypi-firmware soc:firmware: Firmware hash is 194a85abd768c7334bbadc3f1911c10a7d18ed14 Feb 14 10:12:00 volumiokhv kernel: bcm2835-dma 3f007000.dma: DMA legacy API manager, dmachans=0x1 Feb 14 10:12:00 volumiokhv kernel: SCSI subsystem initialized Feb 14 10:12:00 volumiokhv kernel: usbcore: registered new interface driver usbfs Feb 14 10:12:00 volumiokhv kernel: usbcore: registered new interface driver hub Feb 14 10:12:00 volumiokhv kernel: usbcore: registered new device driver usb Feb 14 10:12:00 volumiokhv kernel: clocksource: Switched to clocksource arch_sys_counter Feb 14 10:12:00 volumiokhv kernel: VFS: Disk quotas dquot_6.6.0 Feb 14 10:12:00 volumiokhv kernel: VFS: Dquot-cache hash table entries: 1024 (order 0, 4096 bytes) Feb 14 10:12:00 volumiokhv kernel: FS-Cache: Loaded Feb 14 10:12:00 volumiokhv kernel: CacheFiles: Loaded Feb 14 10:12:00 volumiokhv kernel: thermal_sys: Registered thermal governor 'step_wise' Feb 14 10:12:00 volumiokhv kernel: NET: Registered protocol family 2 Feb 14 10:12:00 volumiokhv kernel: tcp_listen_portaddr_hash hash table entries: 512 (order: 0, 6144 bytes, linear) Feb 14 10:12:00 volumiokhv kernel: TCP established hash table entries: 8192 (order: 3, 32768 bytes, linear) Feb 14 10:12:00 volumiokhv kernel: TCP bind hash table entries: 8192 (order: 4, 65536 bytes, linear) Feb 14 10:12:00 volumiokhv kernel: TCP: Hash tables configured (established 8192 bind 8192) Feb 14 10:12:00 volumiokhv kernel: UDP hash table entries: 512 (order: 2, 16384 bytes, linear) Feb 14 10:12:00 volumiokhv kernel: UDP-Lite hash table entries: 512 (order: 2, 16384 bytes, linear) Feb 14 10:12:00 volumiokhv kernel: NET: Registered protocol family 1 Feb 14 10:12:00 volumiokhv kernel: RPC: Registered named UNIX socket transport module. Feb 14 10:12:00 volumiokhv kernel: RPC: Registered udp transport module. Feb 14 10:12:00 volumiokhv kernel: RPC: Registered tcp transport module. Feb 14 10:12:00 volumiokhv kernel: RPC: Registered tcp NFSv4.1 backchannel transport module. Feb 14 10:12:00 volumiokhv kernel: Trying to unpack rootfs image as initramfs... Feb 14 10:12:00 volumiokhv kernel: Freeing initrd memory: 12592K Feb 14 10:12:00 volumiokhv kernel: hw perfevents: enabled with armv7_cortex_a7 PMU driver, 5 counters available Feb 14 10:12:00 volumiokhv kernel: Initialise system trusted keyrings Feb 14 10:12:00 volumiokhv kernel: workingset: timestamp_bits=14 max_order=18 bucket_order=4 Feb 14 10:12:00 volumiokhv kernel: FS-Cache: Netfs 'nfs' registered for caching Feb 14 10:12:00 volumiokhv kernel: NFS: Registering the id_resolver key type Feb 14 10:12:00 volumiokhv kernel: Key type id_resolver registered Feb 14 10:12:00 volumiokhv kernel: Key type id_legacy registered Feb 14 10:12:00 volumiokhv kernel: nfs4filelayout_init: NFSv4 File Layout Driver Registering... Feb 14 10:12:00 volumiokhv kernel: Key type asymmetric registered Feb 14 10:12:00 volumiokhv kernel: Asymmetric key parser 'x509' registered Feb 14 10:12:00 volumiokhv kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 249) Feb 14 10:12:00 volumiokhv kernel: io scheduler mq-deadline registered Feb 14 10:12:00 volumiokhv kernel: io scheduler kyber registered Feb 14 10:12:00 volumiokhv kernel: bcm2708_fb soc:fb: FB found 1 display(s) Feb 14 10:12:00 volumiokhv kernel: Console: switching to colour frame buffer device 80x30 Feb 14 10:12:00 volumiokhv kernel: bcm2708_fb soc:fb: Registered framebuffer for display 0, size 640x480 Feb 14 10:12:00 volumiokhv kernel: bcm2835-rng 3f104000.rng: hwrng registered Feb 14 10:12:00 volumiokhv kernel: vc-mem: phys_addr:0x00000000 mem_base=0x3ec00000 mem_size:0x40000000(1024 MiB) Feb 14 10:12:00 volumiokhv kernel: vc-sm: Videocore shared memory driver Feb 14 10:12:00 volumiokhv kernel: gpiomem-bcm2835 3f200000.gpiomem: Initialised: Registers at 0x3f200000 Feb 14 10:12:00 volumiokhv kernel: brd: module loaded Feb 14 10:12:00 volumiokhv kernel: loop: module loaded Feb 14 10:12:00 volumiokhv kernel: Loading iSCSI transport class v2.0-870. Feb 14 10:12:00 volumiokhv kernel: libphy: Fixed MDIO Bus: probed Feb 14 10:12:00 volumiokhv kernel: usbcore: registered new interface driver lan78xx Feb 14 10:12:00 volumiokhv kernel: usbcore: registered new interface driver smsc95xx Feb 14 10:12:00 volumiokhv kernel: dwc_otg: version 3.00a 10-AUG-2012 (platform bus) Feb 14 10:12:00 volumiokhv kernel: Core Release: 2.80a Feb 14 10:12:00 volumiokhv kernel: Setting default values for core params Feb 14 10:12:00 volumiokhv kernel: Finished setting default values for core params Feb 14 10:12:00 volumiokhv kernel: Using Buffer DMA mode Feb 14 10:12:00 volumiokhv kernel: Periodic Transfer Interrupt Enhancement - disabled Feb 14 10:12:00 volumiokhv kernel: Multiprocessor Interrupt Enhancement - disabled Feb 14 10:12:00 volumiokhv kernel: OTG VER PARAM: 0, OTG VER FLAG: 0 Feb 14 10:12:00 volumiokhv kernel: Dedicated Tx FIFOs mode Feb 14 10:12:00 volumiokhv kernel: WARN::dwc_otg_hcd_init:1074: FIQ DMA bounce buffers: virt = ba114000 dma = 0xfa114000 len=9024 Feb 14 10:12:00 volumiokhv kernel: FIQ FSM acceleration enabled for : Non-periodic Split Transactions Periodic Split Transactions High-Speed Isochronous Endpoints Interrupt/Control Split Transaction hack enabled Feb 14 10:12:00 volumiokhv kernel: dwc_otg: Microframe scheduler enabled Feb 14 10:12:00 volumiokhv kernel: WARN::hcd_init_fiq:457: FIQ on core 1 Feb 14 10:12:00 volumiokhv kernel: WARN::hcd_init_fiq:458: FIQ ASM at 8070bf24 length 36 Feb 14 10:12:00 volumiokhv kernel: WARN::hcd_init_fiq:497: MPHI regs_base at be810000 Feb 14 10:12:00 volumiokhv kernel: dwc_otg 3f980000.usb: DWC OTG Controller Feb 14 10:12:00 volumiokhv kernel: dwc_otg 3f980000.usb: new USB bus registered, assigned bus number 1 Feb 14 10:12:00 volumiokhv kernel: dwc_otg 3f980000.usb: irq 56, io mem 0x00000000 Feb 14 10:12:00 volumiokhv kernel: Init: Port Power? op_state=1 Feb 14 10:12:00 volumiokhv kernel: Init: Power Port (0) Feb 14 10:12:00 volumiokhv kernel: usb usb1: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 5.04 Feb 14 10:12:00 volumiokhv kernel: usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1 Feb 14 10:12:00 volumiokhv kernel: usb usb1: Product: DWC OTG Controller Feb 14 10:12:00 volumiokhv kernel: usb usb1: Manufacturer: Linux 5.4.83-v7+ dwc_otg_hcd Feb 14 10:12:00 volumiokhv kernel: usb usb1: SerialNumber: 3f980000.usb Feb 14 10:12:00 volumiokhv kernel: hub 1-0:1.0: USB hub found Feb 14 10:12:00 volumiokhv kernel: hub 1-0:1.0: 1 port detected Feb 14 10:12:00 volumiokhv kernel: dwc_otg: FIQ enabled Feb 14 10:12:00 volumiokhv kernel: dwc_otg: NAK holdoff enabled Feb 14 10:12:00 volumiokhv kernel: dwc_otg: FIQ split-transaction FSM enabled Feb 14 10:12:00 volumiokhv kernel: Module dwc_common_port init Feb 14 10:12:00 volumiokhv kernel: usbcore: registered new interface driver usb-storage Feb 14 10:12:00 volumiokhv kernel: mousedev: PS/2 mouse device common for all mice Feb 14 10:12:00 volumiokhv kernel: bcm2835-wdt bcm2835-wdt: Broadcom BCM2835 watchdog timer Feb 14 10:12:00 volumiokhv kernel: sdhci: Secure Digital Host Controller Interface driver Feb 14 10:12:00 volumiokhv kernel: sdhci: Copyright(c) Pierre Ossman Feb 14 10:12:00 volumiokhv kernel: sdhost-bcm2835 3f202000.mmc: could not get clk, deferring probe Feb 14 10:12:00 volumiokhv kernel: sdhci-pltfm: SDHCI platform and OF driver helper Feb 14 10:12:00 volumiokhv kernel: ledtrig-cpu: registered to indicate activity on CPUs Feb 14 10:12:00 volumiokhv kernel: hidraw: raw HID events driver (C) Jiri Kosina Feb 14 10:12:00 volumiokhv kernel: usbcore: registered new interface driver usbhid Feb 14 10:12:00 volumiokhv kernel: usbhid: USB HID core driver Feb 14 10:12:00 volumiokhv kernel: vchiq: vchiq_init_state: slot_zero = (ptrval) Feb 14 10:12:00 volumiokhv kernel: [vc_sm_connected_init]: start Feb 14 10:12:00 volumiokhv kernel: [vc_sm_connected_init]: end - returning 0 Feb 14 10:12:00 volumiokhv kernel: Initializing XFRM netlink socket Feb 14 10:12:00 volumiokhv kernel: NET: Registered protocol family 17 Feb 14 10:12:00 volumiokhv kernel: Key type dns_resolver registered Feb 14 10:12:00 volumiokhv kernel: Registering SWP/SWPB emulation handler Feb 14 10:12:00 volumiokhv kernel: registered taskstats version 1 Feb 14 10:12:00 volumiokhv kernel: Loading compiled-in X.509 certificates Feb 14 10:12:00 volumiokhv kernel: Key type ._fscrypt registered Feb 14 10:12:00 volumiokhv kernel: Key type .fscrypt registered Feb 14 10:12:00 volumiokhv kernel: uart-pl011 3f201000.serial: cts_event_workaround enabled Feb 14 10:12:00 volumiokhv kernel: 3f201000.serial: ttyAMA0 at MMIO 0x3f201000 (irq = 81, base_baud = 0) is a PL011 rev2 Feb 14 10:12:00 volumiokhv kernel: printk: console [ttyAMA0] enabled Feb 14 10:12:00 volumiokhv kernel: bcm2835-power bcm2835-power: Broadcom BCM2835 power domains driver Feb 14 10:12:00 volumiokhv kernel: sdhost: log_buf @ (ptrval) (fa113000) Feb 14 10:12:00 volumiokhv kernel: mmc0: sdhost-bcm2835 loaded - DMA enabled (>1) Feb 14 10:12:00 volumiokhv kernel: of_cfs_init Feb 14 10:12:00 volumiokhv kernel: of_cfs_init: OK Feb 14 10:12:00 volumiokhv kernel: Freeing unused kernel memory: 1024K Feb 14 10:12:00 volumiokhv kernel: Run /init as init process Feb 14 10:12:00 volumiokhv kernel: mmc0: host does not support reading read-only switch, assuming write-enable Feb 14 10:12:00 volumiokhv kernel: mmc0: new high speed SDXC card at address 0001 Feb 14 10:12:00 volumiokhv kernel: mmcblk0: mmc0:0001 EC2QT 59.6 GiB Feb 14 10:12:00 volumiokhv kernel: mmcblk0: p1 p2 p3 Feb 14 10:12:00 volumiokhv kernel: Indeed it is in host mode hprt0 = 00021501 Feb 14 10:12:00 volumiokhv kernel: random: fast init done Feb 14 10:12:00 volumiokhv initramfs: Booting Volumio for BCM2835 Feb 14 10:12:00 volumiokhv initramfs: This script mounts rootfs RO with an overlay RW layer. Feb 14 10:12:00 volumiokhv kernel: usb 1-1: new high-speed USB device number 2 using dwc_otg Feb 14 10:12:00 volumiokhv kernel: Indeed it is in host mode hprt0 = 00001101 Feb 14 10:12:00 volumiokhv kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher Feb 14 10:12:00 volumiokhv initramfs: IMGPART=/dev/mmcblk0p2 Feb 14 10:12:00 volumiokhv initramfs: IMGFILE=/volumio_current.sqsh Feb 14 10:12:00 volumiokhv initramfs: Boot delay (except first time) will be 5 seconds Feb 14 10:12:00 volumiokhv initramfs: /dev/mmcblk0p2: Feb 14 10:12:00 volumiokhv kernel: EXT4-fs (mmcblk0p2): recovery complete Feb 14 10:12:00 volumiokhv kernel: EXT4-fs (mmcblk0p2): mounted filesystem with ordered data mode. Opts: (null) Feb 14 10:12:00 volumiokhv kernel: usb 1-1: New USB device found, idVendor=0424, idProduct=9514, bcdDevice= 2.00 Feb 14 10:12:00 volumiokhv kernel: usb 1-1: New USB device strings: Mfr=0, Product=0, SerialNumber=0 Feb 14 10:12:00 volumiokhv kernel: hub 1-1:1.0: USB hub found Feb 14 10:12:00 volumiokhv kernel: hub 1-1:1.0: 5 ports detected Feb 14 10:12:00 volumiokhv initramfs: Doing a 5 second delay here to give kernel load a headstart Feb 14 10:12:00 volumiokhv kernel: usb 1-1.1: new high-speed USB device number 3 using dwc_otg Feb 14 10:12:00 volumiokhv kernel: usb 1-1.1: New USB device found, idVendor=0424, idProduct=ec00, bcdDevice= 2.00 Feb 14 10:12:00 volumiokhv kernel: usb 1-1.1: New USB device strings: Mfr=0, Product=0, SerialNumber=0 Feb 14 10:12:00 volumiokhv kernel: smsc95xx v1.0.6 Feb 14 10:12:00 volumiokhv kernel: smsc95xx 1-1.1:1.0 eth0: register 'smsc95xx' at usb-3f980000.usb-1.1, smsc95xx USB 2.0 Ethernet, b8:27:eb:3c:2a:53 Feb 14 10:12:00 volumiokhv kernel: usb 1-1.3: new high-speed USB device number 4 using dwc_otg Feb 14 10:12:00 volumiokhv kernel: usb 1-1.3: New USB device found, idVendor=2001, idProduct=3319, bcdDevice= 2.00 Feb 14 10:12:00 volumiokhv kernel: usb 1-1.3: New USB device strings: Mfr=1, Product=2, SerialNumber=3 Feb 14 10:12:00 volumiokhv kernel: usb 1-1.3: Product: Wireless N Nano USB Adapter Feb 14 10:12:00 volumiokhv kernel: usb 1-1.3: Manufacturer: Realtek Feb 14 10:12:00 volumiokhv kernel: usb 1-1.3: SerialNumber: 00e04c000001 Feb 14 10:12:00 volumiokhv initramfs: Checking for USB updates Feb 14 10:12:00 volumiokhv initramfs: No USB device detected (when incorrect, try adding 'bootdelay=5' to your boot cmdline) Feb 14 10:12:00 volumiokhv kernel: EXT4-fs (mmcblk0p3): recovery complete Feb 14 10:12:00 volumiokhv kernel: EXT4-fs (mmcblk0p3): mounted filesystem with ordered data mode. Opts: (null) Feb 14 10:12:00 volumiokhv initramfs: With Option: Feb 14 10:12:00 volumiokhv initramfs: VOLUMIO_VERSION="3.173" Feb 14 10:12:00 volumiokhv initramfs: Finish initramfs, continue booting Volumio Feb 14 10:12:00 volumiokhv systemd[1]: System time before build time, advancing clock. Feb 14 10:12:00 volumiokhv kernel: NET: Registered protocol family 10 Feb 14 10:12:00 volumiokhv kernel: Segment Routing with IPv6 Feb 14 10:12:00 volumiokhv systemd[1]: systemd 241 running in system mode. (+PAM +AUDIT +SELINUX +IMA +APPARMOR +SMACK +SYSVINIT +UTMP +LIBCRYPTSETUP +GCRYPT +GNUTLS +ACL +XZ +LZ4 +SECCOMP +BLKID +ELFUTILS +KMOD -IDN2 +IDN -PCRE2 default-hierarchy=hybrid) Feb 14 10:12:00 volumiokhv systemd[1]: Detected architecture arm. Feb 14 10:12:00 volumiokhv systemd[1]: Set hostname to . Feb 14 10:12:00 volumiokhv kernel: uart-pl011 3f201000.serial: no DMA platform data Feb 14 10:12:00 volumiokhv systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Feb 14 10:12:00 volumiokhv kernel: random: systemd: uninitialized urandom read (16 bytes read) Feb 14 10:12:00 volumiokhv kernel: random: systemd: uninitialized urandom read (16 bytes read) Feb 14 10:12:00 volumiokhv kernel: random: systemd: uninitialized urandom read (16 bytes read) Feb 14 10:12:00 volumiokhv systemd[1]: Listening on Journal Socket (/dev/log). Feb 14 10:12:00 volumiokhv systemd[1]: Listening on initctl Compatibility Named Pipe. Feb 14 10:12:00 volumiokhv systemd[1]: Listening on Journal Socket. Feb 14 10:12:00 volumiokhv systemd[1]: Starting Remount Root and Kernel File Systems... Feb 14 10:12:00 volumiokhv systemd[1]: Condition check resulted in Kernel Module supporting RPCSEC_GSS being skipped. Feb 14 10:12:00 volumiokhv systemd[1]: Listening on udev Kernel Socket. Feb 14 10:12:00 volumiokhv kernel: i2c /dev entries driver Feb 14 10:12:00 volumiokhv systemd-journald[173]: Journal started Feb 14 10:12:00 volumiokhv systemd-journald[173]: Runtime journal (/run/log/journal/6a1856d5ec373010caf24f6d61b094d2) is 3.7M, max 30.0M, 26.2M free. Feb 14 10:12:00 volumiokhv systemd-modules-load[182]: Inserted module 'i2c_dev' Dec 11 10:56:57 volumiokhv fake-hwclock[181]: Sat Dec 11 10:56:57 UTC 2021 Dec 11 10:56:57 volumiokhv systemd[1]: Started Restore / save the current clock. Dec 11 10:56:57 volumiokhv systemd[1]: Mounted Kernel Debug File System. Dec 11 10:56:57 volumiokhv systemd[1]: Mounted Kernel Configuration File System. Dec 11 10:56:57 volumiokhv systemd[1]: Started Load/Save Random Seed. Dec 11 10:56:57 volumiokhv systemd[1]: Started Apply Kernel Variables. Dec 11 10:56:57 volumiokhv systemd[1]: dynamicswap.service: Succeeded. Dec 11 10:56:57 volumiokhv systemd[1]: Started Create System Users. Dec 11 10:56:57 volumiokhv systemd[1]: Starting Create Static Device Nodes in /dev... Dec 11 10:56:57 volumiokhv systemd[1]: Started udev Coldplug all Devices. Dec 11 10:56:57 volumiokhv systemd[1]: Starting Helper to synchronize boot up for ifupdown... Dec 11 10:56:57 volumiokhv systemd[1]: Started Create Static Device Nodes in /dev. Dec 11 10:56:57 volumiokhv systemd[1]: Starting udev Kernel Device Manager... Dec 11 10:56:57 volumiokhv systemd[1]: Reached target Local File Systems (Pre). Dec 11 10:56:57 volumiokhv systemd[1]: var-log.mount: Directory /var/log to mount over is not empty, mounting anyway. Dec 11 10:56:57 volumiokhv systemd[1]: Mounting /var/log... Dec 11 10:56:57 volumiokhv systemd[1]: Mounting /var/spool/cups... Dec 11 10:56:57 volumiokhv systemd[1]: tmp.mount: Directory /tmp to mount over is not empty, mounting anyway. Dec 11 10:56:57 volumiokhv systemd[1]: Mounting /tmp... Dec 11 10:56:57 volumiokhv systemd[1]: Started Helper to synchronize boot up for ifupdown. Dec 11 10:56:57 volumiokhv systemd[1]: Mounted /var/log. Dec 11 10:56:57 volumiokhv systemd[1]: Mounted /var/spool/cups. Dec 11 10:56:57 volumiokhv systemd[1]: Mounted /tmp. Dec 11 10:56:57 volumiokhv systemd[1]: Mounting /var/spool/cups/tmp... Dec 11 10:56:57 volumiokhv systemd[1]: Starting Flush Journal to Persistent Storage... Dec 11 10:56:57 volumiokhv systemd-udevd[206]: Network interface NamePolicy= disabled on kernel command line, ignoring. Dec 11 10:56:57 volumiokhv systemd[1]: Mounted /var/spool/cups/tmp. Dec 11 10:56:57 volumiokhv systemd[1]: Started udev Kernel Device Manager. Dec 11 10:56:57 volumiokhv systemd[1]: Starting Show Plymouth Boot Screen... Dec 11 10:56:57 volumiokhv systemd-journald[173]: Runtime journal (/run/log/journal/6a1856d5ec373010caf24f6d61b094d2) is 7.5M, max 30.0M, 22.5M free. Dec 11 10:56:57 volumiokhv systemd[1]: Started Flush Journal to Persistent Storage. Dec 11 10:56:57 volumiokhv systemd[1]: Received SIGRTMIN+20 from PID 223 (plymouthd). Dec 11 10:56:57 volumiokhv systemd[1]: Started Show Plymouth Boot Screen. Dec 11 10:56:57 volumiokhv systemd[1]: Condition check resulted in Dispatch Password Requests to Console Directory Watch being skipped. Dec 11 10:56:57 volumiokhv systemd[1]: Reached target Local Encrypted Volumes. Dec 11 10:56:57 volumiokhv systemd[1]: Reached target Paths. Dec 11 10:56:57 volumiokhv systemd[1]: Started Forward Password Requests to Plymouth Directory Watch. Dec 11 10:56:58 volumiokhv kernel: vc_sm_cma: module is from the staging directory, the quality is unknown, you have been warned. Dec 11 10:56:58 volumiokhv kernel: bcm2835_vc_sm_cma_probe: Videocore shared memory driver Dec 11 10:56:58 volumiokhv kernel: [vc_sm_connected_init]: start Dec 11 10:56:58 volumiokhv kernel: mc: Linux media interface: v0.10 Dec 11 10:56:58 volumiokhv kernel: [vc_sm_connected_init]: installed successfully Dec 11 10:56:58 volumiokhv kernel: videodev: Linux video capture interface: v2.00 Dec 11 10:56:58 volumiokhv kernel: bcm2835_mmal_vchiq: module is from the staging directory, the quality is unknown, you have been warned. Dec 11 10:56:58 volumiokhv systemd[1]: Found device /dev/ttyAMA0. Dec 11 10:56:58 volumiokhv kernel: bcm2835_mmal_vchiq: module is from the staging directory, the quality is unknown, you have been warned. Dec 11 10:56:58 volumiokhv kernel: bcm2835_isp: module is from the staging directory, the quality is unknown, you have been warned. Dec 11 10:56:58 volumiokhv kernel: bcm2835_codec: module is from the staging directory, the quality is unknown, you have been warned. Dec 11 10:56:58 volumiokhv kernel: bcm2835_v4l2: module is from the staging directory, the quality is unknown, you have been warned. Dec 11 10:56:58 volumiokhv kernel: bcm2835-isp bcm2835-isp: Device node output[0] registered as /dev/video13 Dec 11 10:56:58 volumiokhv kernel: bcm2835-isp bcm2835-isp: Device node capture[0] registered as /dev/video14 Dec 11 10:56:58 volumiokhv kernel: bcm2835-isp bcm2835-isp: Device node capture[1] registered as /dev/video15 Dec 11 10:56:58 volumiokhv kernel: bcm2835-isp bcm2835-isp: Device node stats[2] registered as /dev/video16 Dec 11 10:56:58 volumiokhv kernel: bcm2835-isp bcm2835-isp: Register output node 0 with media controller Dec 11 10:56:58 volumiokhv kernel: bcm2835-isp bcm2835-isp: Register capture node 1 with media controller Dec 11 10:56:58 volumiokhv kernel: bcm2835-isp bcm2835-isp: Register capture node 2 with media controller Dec 11 10:56:58 volumiokhv kernel: bcm2835-isp bcm2835-isp: Register capture node 3 with media controller Dec 11 10:56:58 volumiokhv kernel: bcm2835-isp bcm2835-isp: Loaded V4L2 bcm2835-isp Dec 11 10:56:58 volumiokhv kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video10 Dec 11 10:56:58 volumiokhv kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 decode Dec 11 10:56:58 volumiokhv kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video11 Dec 11 10:56:58 volumiokhv kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 encode Dec 11 10:56:58 volumiokhv kernel: snd_bcm2835: module is from the staging directory, the quality is unknown, you have been warned. Dec 11 10:56:58 volumiokhv kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video12 Dec 11 10:56:58 volumiokhv kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 isp Dec 11 10:56:58 volumiokhv kernel: snd-rpi-iqaudio-dac soc:sound: ASoC: failed to init link IQaudIO DAC: -517 Dec 11 10:56:58 volumiokhv kernel: bcm2835_audio bcm2835_audio: card created with 4 channels Dec 11 10:56:58 volumiokhv kernel: bcm2835_audio bcm2835_audio: card created with 4 channels Dec 11 10:56:58 volumiokhv kernel: snd-rpi-iqaudio-dac soc:sound: ASoC: failed to init link IQaudIO DAC: -517 Dec 11 10:56:58 volumiokhv systemd-udevd[214]: Using default interface naming scheme 'v240'. Dec 11 10:56:59 volumiokhv systemd-udevd[222]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Dec 11 10:56:59 volumiokhv systemd-udevd[214]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Dec 11 10:56:59 volumiokhv kernel: cfg80211: Loading compiled-in X.509 certificates for regulatory database Dec 11 10:56:59 volumiokhv systemd[1]: Found device /dev/mmcblk0p1. Dec 11 10:56:59 volumiokhv kernel: cfg80211: Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7' Dec 11 10:57:00 volumiokhv kernel: snd-rpi-iqaudio-dac soc:sound: pcm512x-hifi <-> 3f203000.i2s mapping ok Dec 11 10:57:00 volumiokhv kernel: snd-rpi-iqaudio-dac soc:sound: snd_rpi_iqaudio_gpio_unmute: un-muting amp using GPIO22 Dec 11 10:57:00 volumiokhv kernel: 8192eu: loading out-of-tree module taints kernel. Dec 11 10:57:00 volumiokhv kernel: usbcore: registered new interface driver rtl8192eu Dec 11 10:57:00 volumiokhv systemd-udevd[212]: Using default interface naming scheme 'v240'. Dec 11 10:57:00 volumiokhv systemd-udevd[212]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Dec 11 10:57:01 volumiokhv systemd[1]: Condition check resulted in SMSC9512/9514 Fast Ethernet Adapter being skipped. Dec 11 10:57:01 volumiokhv systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch. Dec 11 10:57:01 volumiokhv systemd[1]: Condition check resulted in Huge Pages File System being skipped. Dec 11 10:57:01 volumiokhv systemd[1]: Condition check resulted in Set Up Additional Binary Formats being skipped. Dec 11 10:57:01 volumiokhv systemd[1]: Condition check resulted in Dispatch Password Requests to Console Directory Watch being skipped. Dec 11 10:57:01 volumiokhv systemd[1]: Condition check resulted in Rebuild Hardware Database being skipped. Dec 11 10:57:01 volumiokhv systemd[1]: Condition check resulted in FUSE Control File System being skipped. Dec 11 10:57:01 volumiokhv systemd[1]: Starting File System Check on /dev/mmcblk0p1... Dec 11 10:57:01 volumiokhv systemd[1]: Starting Load/Save RF Kill Switch Status... Dec 11 10:57:01 volumiokhv systemd[1]: Found device Wireless_N_Nano_USB_Adapter. Dec 11 10:57:01 volumiokhv systemd[1]: Started Load/Save RF Kill Switch Status. Dec 11 10:57:01 volumiokhv systemd[1]: Started File System Check Daemon to report status. Dec 11 10:57:01 volumiokhv systemd-fsck[316]: fsck.fat 4.1 (2017-01-24) Dec 11 10:57:01 volumiokhv systemd-fsck[316]: /dev/mmcblk0p1: 254 files, 26169/46774 clusters Dec 11 10:57:01 volumiokhv systemd[1]: Started File System Check on /dev/mmcblk0p1. Dec 11 10:57:01 volumiokhv systemd[1]: Mounting /boot... Dec 11 10:57:01 volumiokhv systemd[1]: Mounted /boot. Dec 11 10:57:01 volumiokhv systemd[1]: Reached target Local File Systems. Dec 11 10:57:01 volumiokhv systemd[1]: Condition check resulted in Commit a transient machine-id on disk being skipped. Dec 11 10:57:01 volumiokhv systemd[1]: Started ifup for eth0. Dec 11 10:57:01 volumiokhv systemd[1]: Starting Create Volatile Files and Directories... Dec 11 10:57:01 volumiokhv systemd[1]: Starting Raise network interfaces... Dec 11 10:57:01 volumiokhv systemd[1]: Started ifup for wlan0. Dec 11 10:57:01 volumiokhv systemd[1]: Starting Preprocess NFS configuration... Dec 11 10:57:01 volumiokhv systemd[1]: Starting Tell Plymouth To Write Out Runtime Data... Dec 11 10:57:01 volumiokhv systemd[1]: Received SIGRTMIN+20 from PID 223 (plymouthd). Dec 11 10:57:01 volumiokhv systemd[1]: plymouth-read-write.service: Succeeded. Dec 11 10:57:01 volumiokhv systemd[1]: Started Tell Plymouth To Write Out Runtime Data. Dec 11 10:57:02 volumiokhv systemd[1]: nfs-config.service: Succeeded. Dec 11 10:57:02 volumiokhv systemd[1]: Started Preprocess NFS configuration. Dec 11 10:57:02 volumiokhv systemd[1]: Condition check resulted in RPC security service for NFS client and server being skipped. Dec 11 10:57:02 volumiokhv systemd[1]: Condition check resulted in RPC security service for NFS server being skipped. Dec 11 10:57:02 volumiokhv systemd[1]: Reached target NFS client services. Dec 11 10:57:02 volumiokhv systemd[1]: Started Create Volatile Files and Directories. Dec 11 10:57:02 volumiokhv systemd[1]: Starting RPC bind portmap service... Dec 11 10:57:02 volumiokhv systemd[1]: Starting Update UTMP about System Boot/Shutdown... Dec 11 10:57:02 volumiokhv systemd[1]: Condition check resulted in Network Time Synchronization being skipped. Dec 11 10:57:02 volumiokhv systemd[1]: Started Entropy daemon using the HAVEGE algorithm. Dec 11 10:57:02 volumiokhv systemd[1]: Started Update UTMP about System Boot/Shutdown. Dec 11 10:57:02 volumiokhv systemd[1]: Reached target System Initialization. Dec 11 10:57:02 volumiokhv systemd[1]: Listening on triggerhappy.socket. Dec 11 10:57:02 volumiokhv kernel: 8021q: 802.1Q VLAN Support v1.8 Dec 11 10:57:02 volumiokhv systemd[1]: Listening on mpd.socket. Dec 11 10:57:02 volumiokhv systemd[1]: Started Daily Cleanup of Temporary Directories. Dec 11 10:57:02 volumiokhv systemd[1]: Started Daily apt download activities. Dec 11 10:57:02 volumiokhv systemd[1]: Started Daily apt upgrade and clean activities. Dec 11 10:57:02 volumiokhv systemd[1]: Reached target Timers. Dec 11 10:57:02 volumiokhv systemd[1]: Listening on Avahi mDNS/DNS-SD Stack Activation Socket. Dec 11 10:57:02 volumiokhv systemd[1]: Listening on D-Bus System Message Bus Socket. Dec 11 10:57:02 volumiokhv systemd[1]: Reached target Sockets. Dec 11 10:57:02 volumiokhv systemd[1]: Reached target Basic System. Dec 11 10:57:02 volumiokhv systemd[1]: Condition check resulted in Volumio SSH enabler being skipped. Dec 11 10:57:02 volumiokhv systemd[1]: Started Volumio Iptables Module. Dec 11 10:57:02 volumiokhv systemd[1]: Condition check resulted in Configure Bluetooth Modems connected by UART being skipped. Dec 11 10:57:02 volumiokhv systemd[1]: Condition check resulted in getty on tty2-tty6 if dbus and logind are not available being skipped. Dec 11 10:57:02 volumiokhv systemd[1]: Starting triggerhappy global hotkey daemon... Dec 11 10:57:02 volumiokhv systemd[1]: Started UPnP Renderer front-end to MPD. Dec 11 10:57:02 volumiokhv systemd[1]: Started volumio-remote-updater.service. Dec 11 10:57:02 volumiokhv systemd[1]: Starting Wireless Services... Dec 11 10:57:02 volumiokhv thd[393]: Unable to parse trigger line: Dec 11 10:57:02 volumiokhv thd[393]: Unable to parse trigger line: KEY_MIN_INTERESTING 1 /usr/local/bin/volumio volume toggle Dec 11 10:57:02 volumiokhv thd[393]: Unable to parse trigger line: Dec 11 10:57:02 volumiokhv systemd[1]: Starting Login Service... Dec 11 10:57:02 volumiokhv systemd[1]: Started D-Bus System Message Bus. Dec 11 10:57:02 volumiokhv systemd[1]: Starting Avahi mDNS/DNS-SD Stack... Dec 11 10:57:02 volumiokhv thd[393]: Found socket passed from systemd Dec 11 10:57:02 volumiokhv alsactl[403]: alsactl 1.1.8 daemon started Dec 11 10:57:02 volumiokhv systemd[1]: Started Manage Sound Card State (restore and store). Dec 11 10:57:02 volumiokhv systemd[1]: Starting Save/Restore Sound Card State... Dec 11 10:57:02 volumiokhv systemd[1]: Starting WPA supplicant... Dec 11 10:57:02 volumiokhv systemd[1]: Condition check resulted in Copy user wpa_supplicant.conf being skipped. Dec 11 10:57:02 volumiokhv systemd[1]: Starting dhcpcd on all interfaces... Dec 11 10:57:02 volumiokhv systemd[1]: Started triggerhappy global hotkey daemon. Dec 11 10:57:02 volumiokhv kernel: random: crng init done Dec 11 10:57:02 volumiokhv kernel: random: 7 urandom warning(s) missed due to ratelimiting Dec 11 10:57:02 volumiokhv dhcpcd[408]: Not running dhcpcd because /etc/network/interfaces Dec 11 10:57:02 volumiokhv dhcpcd[408]: defines some interfaces that will use a Dec 11 10:57:02 volumiokhv dhcpcd[408]: DHCP client or static address Dec 11 10:57:03 volumiokhv avahi-daemon[402]: Found user 'avahi' (UID 110) and group 'avahi' (GID 113). Dec 11 10:57:03 volumiokhv avahi-daemon[402]: Successfully dropped root privileges. Dec 11 10:57:03 volumiokhv avahi-daemon[402]: avahi-daemon 0.7 starting up. Dec 11 10:57:03 volumiokhv systemd-logind[398]: New seat seat0. Dec 11 10:57:03 volumiokhv systemd[1]: Started Save/Restore Sound Card State. Dec 11 10:57:03 volumiokhv systemd[1]: dhcpcd.service: Control process exited, code=exited, status=6/NOTCONFIGURED Dec 11 10:57:03 volumiokhv systemd[1]: dhcpcd.service: Failed with result 'exit-code'. Dec 11 10:57:03 volumiokhv systemd[1]: Failed to start dhcpcd on all interfaces. Dec 11 10:57:03 volumiokhv avahi-daemon[402]: Successfully called chroot(). Dec 11 10:57:03 volumiokhv avahi-daemon[402]: Successfully dropped remaining capabilities. Dec 11 10:57:03 volumiokhv systemd[1]: Started Login Service. Dec 11 10:57:03 volumiokhv systemd[1]: Started Avahi mDNS/DNS-SD Stack. Dec 11 10:57:03 volumiokhv systemd[1]: Reached target Sound Card. Dec 11 10:57:03 volumiokhv avahi-daemon[402]: Loading service file /services/volumio.service. Dec 11 10:57:03 volumiokhv systemd[1]: Started WPA supplicant. Dec 11 10:57:03 volumiokhv wpa_supplicant[407]: Successfully initialized wpa_supplicant Dec 11 10:57:03 volumiokhv systemd[1]: Started RPC bind portmap service. Dec 11 10:57:03 volumiokhv systemd[1]: Reached target Remote File Systems (Pre). Dec 11 10:57:03 volumiokhv systemd[1]: Reached target Remote File Systems. Dec 11 10:57:03 volumiokhv systemd[1]: Starting LSB: Load kernel modules needed to enable cpufreq scaling... Dec 11 10:57:04 volumiokhv systemd[1]: Reached target RPC Port Mapper. Dec 11 10:57:04 volumiokhv sh[322]: eth0: waiting for carrier Dec 11 10:57:04 volumiokhv kernel: smsc95xx 1-1.1:1.0 eth0: hardware isn't capable of remote wakeup Dec 11 10:57:04 volumiokhv dhcpcd[369]: eth0: waiting for carrier Dec 11 10:57:04 volumiokhv avahi-daemon[402]: Network interface enumeration completed. Dec 11 10:57:04 volumiokhv avahi-daemon[402]: Server startup complete. Host name is volumiokhv.local. Local service cookie is 1412872120. Dec 11 10:57:04 volumiokhv avahi-daemon[402]: Service "VolumioKHV" (/services/volumio.service) successfully established. Dec 11 10:57:04 volumiokhv sh[327]: wlan0=wlan0 Dec 11 10:57:04 volumiokhv systemd[1]: Started Raise network interfaces. Dec 11 10:57:04 volumiokhv systemd[1]: Reached target Network. Dec 11 10:57:04 volumiokhv systemd[1]: Starting Network Time Service... Dec 11 10:57:04 volumiokhv systemd[1]: Condition check resulted in fast remote file copy program daemon being skipped. Dec 11 10:57:04 volumiokhv volumio-remote-updater[395]: Error: No active session Dec 11 10:57:04 volumiokhv systemd[1]: Starting Music Player Daemon... Dec 11 10:57:04 volumiokhv systemd[1]: Reached target Network is Online. Dec 11 10:57:04 volumiokhv volumio-remote-updater[395]: [2021-12-11 10:57:04] [info] asio async_connect error: system:111 (Connection refused) Dec 11 10:57:04 volumiokhv volumio-remote-updater[395]: [2021-12-11 10:57:04] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Dec 11 10:57:04 volumiokhv volumio-remote-updater[395]: [2021-12-11 10:57:04] [error] handle_connect error: Underlying Transport Error Dec 11 10:57:04 volumiokhv systemd[1]: Starting /etc/rc.local Compatibility... Dec 11 10:57:04 volumiokhv systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Dec 11 10:57:04 volumiokhv loadcpufreq[431]: Loading cpufreq kernel modules...done (none). Dec 11 10:57:04 volumiokhv systemd[1]: Starting LSB: Brings up/down network automatically... Dec 11 10:57:05 volumiokhv systemd[1]: Starting Samba NMB Daemon... Dec 11 10:57:05 volumiokhv systemd[1]: Starting Permit User Sessions... Dec 11 10:57:05 volumiokhv systemd[1]: Started LSB: Load kernel modules needed to enable cpufreq scaling. Dec 11 10:57:05 volumiokhv systemd[1]: Started /etc/rc.local Compatibility. Dec 11 10:57:05 volumiokhv systemd[1]: Started Permit User Sessions. Dec 11 10:57:05 volumiokhv systemd[1]: Starting Hold until boot process finishes up... Dec 11 10:57:05 volumiokhv systemd[1]: Starting Terminate Plymouth Boot Screen... Dec 11 10:57:05 volumiokhv volumio[394]: Could not open config: /tmp/upmpdcli.conf Dec 11 10:57:05 volumiokhv systemd[1]: Starting LSB: set CPUFreq kernel parameters... Dec 11 10:57:05 volumiokhv systemd[1]: Received SIGRTMIN+21 from PID 223 (plymouthd). Dec 11 10:57:05 volumiokhv systemd[1]: iptables.service: Succeeded. Dec 11 10:57:05 volumiokhv ifplugd(eth0)[545]: ifplugd 0.28 initializing. Dec 11 10:57:05 volumiokhv ifplugd(eth0)[545]: Using interface eth0/B8:27:EB:3C:2A:53 with driver (version: 22-Aug-2005) Dec 11 10:57:05 volumiokhv ifplugd(eth0)[545]: Using detection mode: SIOCETHTOOL Dec 11 10:57:05 volumiokhv ifplugd(eth0)[545]: Initialization complete, link beat not detected. Dec 11 10:57:05 volumiokhv ntpd[504]: ntpd 4.2.8p12@1.3728-o (1): Starting Dec 11 10:57:05 volumiokhv ntpd[504]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 103:103 Dec 11 10:57:05 volumiokhv systemd[1]: plymouth-start.service: Succeeded. Dec 11 10:57:05 volumiokhv systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Dec 11 10:57:05 volumiokhv systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Dec 11 10:57:05 volumiokhv systemd[1]: Started Network Time Service. Dec 11 10:57:05 volumiokhv haveged[372]: haveged: ver: 1.9.1; arch: generic; vend: ; build: (gcc 8.2.0 CTV); collect: 128K Dec 11 10:57:05 volumiokhv haveged[372]: haveged: cpu: (VC); data: 16K (D); inst: 16K (D); idx: 11/40; sz: 14768/63488 Dec 11 10:57:05 volumiokhv haveged[372]: haveged: tot tests(BA8): A:1/1 B:1/1 continuous tests(B): last entropy estimate 7.99926 Dec 11 10:57:05 volumiokhv haveged[372]: haveged: fills: 0, generated: 0 Dec 11 10:57:05 volumiokhv systemd[1]: plymouth-quit-wait.service: Succeeded. Dec 11 10:57:05 volumiokhv systemd[1]: Started Hold until boot process finishes up. Dec 11 10:57:05 volumiokhv systemd[1]: plymouth-quit.service: Succeeded. Dec 11 10:57:05 volumiokhv systemd[1]: Started Terminate Plymouth Boot Screen. Dec 11 10:57:05 volumiokhv systemd[1]: Received SIGRTMIN+21 from PID 223 (n/a). Dec 11 10:57:05 volumiokhv ifplugd[518]: Network Interface Plugging Daemon...start eth0...done. Dec 11 10:57:05 volumiokhv systemd[1]: Started LSB: Brings up/down network automatically. Dec 11 10:57:05 volumiokhv ntpd[546]: proto: precision = 1.771 usec (-19) Dec 11 10:57:05 volumiokhv systemd[1]: Started Serial Getty on ttyAMA0. Dec 11 10:57:05 volumiokhv systemd[1]: Started Getty on tty1. Dec 11 10:57:05 volumiokhv systemd[1]: Reached target Login Prompts. Dec 11 10:57:05 volumiokhv ntpd[546]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Dec 11 10:57:05 volumiokhv ntpd[546]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2022-06-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Dec 11 10:57:05 volumiokhv ntpd[546]: Listen and drop on 0 v6wildcard [::]:123 Dec 11 10:57:05 volumiokhv ntpd[546]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Dec 11 10:57:05 volumiokhv ntpd[546]: Listen normally on 2 lo 127.0.0.1:123 Dec 11 10:57:05 volumiokhv ntpd[546]: Listening on routing socket on fd #19 for interface updates Dec 11 10:57:05 volumiokhv ntpd[546]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Dec 11 10:57:05 volumiokhv ntpd[546]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Dec 11 10:57:05 volumiokhv cpufrequtils[533]: CPUFreq Utilities: Setting performance CPUFreq governor...CPU0...CPU1...CPU2...CPU3...done. Dec 11 10:57:05 volumiokhv systemd[1]: Started LSB: set CPUFreq kernel parameters. Dec 11 10:57:06 volumiokhv systemd[1]: systemd-rfkill.service: Succeeded. Dec 11 10:57:06 volumiokhv ntpd[546]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Dec 11 10:57:07 volumiokhv nmbd[526]: [2021/12/11 10:57:07.196345, 0] ../source3/nmbd/asyncdns.c:158(start_async_dns) Dec 11 10:57:07 volumiokhv nmbd[526]: started asyncdns process 568 Dec 11 10:57:07 volumiokhv nmbd[526]: [2021/12/11 10:57:07.202991, 0] ../lib/util/become_daemon.c:149(daemon_status) Dec 11 10:57:07 volumiokhv nmbd[526]: daemon_status: STATUS=daemon 'nmbd' : No local IPv4 non-loopback interfaces available, waiting for interface ... Dec 11 10:57:07 volumiokhv nmbd[526]: [2021/12/11 10:57:07.204178, 0] ../source3/nmbd/nmbd_subnetdb.c:254(create_subnets) Dec 11 10:57:07 volumiokhv nmbd[526]: NOTE: NetBIOS name resolution is not supported for Internet Protocol Version 6 (IPv6). Dec 11 10:57:07 volumiokhv ntpd[546]: error resolving pool 1.debian.pool.ntp.org: System error (-11) Dec 11 10:57:08 volumiokhv ntpd[546]: error resolving pool 2.debian.pool.ntp.org: System error (-11) Dec 11 10:57:09 volumiokhv wireless.js[396]: WIRELESS DAEMON: start Dec 11 10:57:09 volumiokhv wireless.js[396]: WIRELESS: Loaded configuration Dec 11 10:57:09 volumiokhv wireless.js[396]: Cleaning previous... Dec 11 10:57:09 volumiokhv ntpd[546]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Dec 11 10:57:09 volumiokhv sudo[573]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Dec 11 10:57:09 volumiokhv sudo[573]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 11 10:57:09 volumiokhv sudo[573]: pam_unix(sudo:session): session closed for user root Dec 11 10:57:09 volumiokhv sudo[575]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Dec 11 10:57:09 volumiokhv sudo[575]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 11 10:57:09 volumiokhv sudo[575]: pam_unix(sudo:session): session closed for user root Dec 11 10:57:09 volumiokhv volumio-remote-updater[395]: [2021-12-11 10:57:09] [info] asio async_connect error: system:111 (Connection refused) Dec 11 10:57:09 volumiokhv volumio-remote-updater[395]: [2021-12-11 10:57:09] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Dec 11 10:57:09 volumiokhv volumio-remote-updater[395]: [2021-12-11 10:57:09] [error] handle_connect error: Underlying Transport Error Dec 11 10:57:09 volumiokhv wireless.js[396]: Stopped aP Dec 11 10:57:09 volumiokhv wireless.js[396]: Start wireless flow Dec 11 10:57:09 volumiokhv wireless.js[396]: Stopped hotspot (if there).. Dec 11 10:57:10 volumiokhv sudo[582]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Dec 11 10:57:10 volumiokhv sudo[582]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 11 10:57:10 volumiokhv sudo[582]: pam_unix(sudo:session): session closed for user root Dec 11 10:57:10 volumiokhv sudo[584]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Dec 11 10:57:10 volumiokhv sudo[584]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 11 10:57:10 volumiokhv sudo[584]: pam_unix(sudo:session): session closed for user root Dec 11 10:57:10 volumiokhv wireless.js[396]: DHCP IP Dec 11 10:57:10 volumiokhv wireless.js[396]: Start ap Dec 11 10:57:10 volumiokhv wpa_supplicant[586]: Successfully initialized wpa_supplicant Dec 11 10:57:10 volumiokhv sudo[587]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/dhcpcd Dec 11 10:57:10 volumiokhv sudo[587]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 11 10:57:10 volumiokhv dhcpcd[588]: dev: loaded udev Dec 11 10:57:10 volumiokhv dhcpcd-run-hooks[606]: wlan0: starting wpa_supplicant Dec 11 10:57:10 volumiokhv mpd[562]: Dec 11 10:57 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 11 10:57:11 volumiokhv wireless.js[396]: trying... Dec 11 10:57:11 volumiokhv mpd[562]: Dec 11 10:57 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Dec 11 10:57:11 volumiokhv mpd[562]: Dec 11 10:57 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Dec 11 10:57:11 volumiokhv systemd[1]: Started Music Player Daemon. Dec 11 10:57:11 volumiokhv sudo[610]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Dec 11 10:57:11 volumiokhv sudo[610]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 11 10:57:11 volumiokhv sudo[610]: pam_unix(sudo:session): session closed for user root Dec 11 10:57:11 volumiokhv dhcpcd-run-hooks[614]: wlan0: failed to start wpa_supplicant Dec 11 10:57:11 volumiokhv dhcpcd-run-hooks[615]: wlan0: Successfully initialized wpa_supplicant ctrl_iface exists and seems to be in use - cannot override it Delete '/var/run/wpa_supplicant/wlan0' manually if it is not used anymore Failed to initialize control interface '/var/run/wpa_supplicant'. You may have another wpa_supplicant process already running or the file was left by an unclean termination of wpa_supplicant in which case you will need to manually remove this file before starting wpa_supplicant again. nl80211: deinit ifname=wlan0 disabled_11b_rates=0 Dec 11 10:57:11 volumiokhv dhcpcd[588]: wlan0: connected to Access Point `' Dec 11 10:57:11 volumiokhv dhcpcd[588]: no interfaces have a carrier Dec 11 10:57:11 volumiokhv dhcpcd[588]: forked to background, child pid 625 Dec 11 10:57:11 volumiokhv dhcpcd[625]: eth0: waiting for carrier Dec 11 10:57:11 volumiokhv dhcpcd[625]: wlan0: waiting for carrier Dec 11 10:57:11 volumiokhv sudo[587]: pam_unix(sudo:session): session closed for user root Dec 11 10:57:12 volumiokhv wireless.js[396]: trying... Dec 11 10:57:12 volumiokhv sudo[627]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Dec 11 10:57:12 volumiokhv sudo[627]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 11 10:57:12 volumiokhv sudo[627]: pam_unix(sudo:session): session closed for user root Dec 11 10:57:13 volumiokhv wpa_supplicant[608]: wlan0: Trying to associate with 60:e3:27:4f:c2:5d (SSID='icanhearyouhavingsex' freq=2462 MHz) Dec 11 10:57:13 volumiokhv wireless.js[396]: trying... Dec 11 10:57:13 volumiokhv sudo[630]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Dec 11 10:57:13 volumiokhv sudo[630]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 11 10:57:13 volumiokhv sudo[630]: pam_unix(sudo:session): session closed for user root Dec 11 10:57:13 volumiokhv dhcpcd[625]: wlan0: carrier acquired Dec 11 10:57:13 volumiokhv wpa_supplicant[608]: wlan0: Associated with 60:e3:27:4f:c2:5d Dec 11 10:57:13 volumiokhv wpa_supplicant[608]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Dec 11 10:57:13 volumiokhv dhcpcd[625]: wlan0: connected to Access Point `icanhearyouhavingsex' Dec 11 10:57:13 volumiokhv wpa_supplicant[608]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=US Dec 11 10:57:13 volumiokhv wpa_supplicant[608]: wlan0: WPA: Key negotiation completed with 60:e3:27:4f:c2:5d [PTK=CCMP GTK=CCMP] Dec 11 10:57:13 volumiokhv wpa_supplicant[608]: wlan0: CTRL-EVENT-CONNECTED - Connection to 60:e3:27:4f:c2:5d completed [id=0 id_str=] Dec 11 10:57:13 volumiokhv dhcpcd[625]: DUID 00:01:00:01:29:43:52:fe:b8:27:eb:3c:2a:53 Dec 11 10:57:13 volumiokhv dhcpcd[625]: wlan0: IAID 33:32:eb:88 Dec 11 10:57:13 volumiokhv dhcpcd[625]: wlan0: adding address fe80::3c8d:c182:7f6b:20ae Dec 11 10:57:13 volumiokhv dhcpcd[625]: ipv6_addaddr1: Permission denied Dec 11 10:57:13 volumiokhv dhcpcd[625]: wlan0: carrier lost Dec 11 10:57:13 volumiokhv dhcpcd[625]: wlan0: carrier acquired Dec 11 10:57:13 volumiokhv dhcpcd[625]: wlan0: IAID 33:32:eb:88 Dec 11 10:57:14 volumiokhv dhcpcd[625]: wlan0: soliciting an IPv6 router Dec 11 10:57:14 volumiokhv wireless.js[396]: trying... Dec 11 10:57:14 volumiokhv sudo[645]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Dec 11 10:57:14 volumiokhv sudo[645]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 11 10:57:14 volumiokhv sudo[645]: pam_unix(sudo:session): session closed for user root Dec 11 10:57:14 volumiokhv dhcpcd[625]: wlan0: rebinding lease of 192.168.1.65 Dec 11 10:57:14 volumiokhv dhcpcd[625]: wlan0: probing address 192.168.1.65/24 Dec 11 10:57:15 volumiokhv wireless.js[396]: trying... Dec 11 10:57:15 volumiokhv sudo[648]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Dec 11 10:57:15 volumiokhv sudo[648]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 11 10:57:15 volumiokhv sudo[648]: pam_unix(sudo:session): session closed for user root Dec 11 10:57:16 volumiokhv wireless.js[396]: trying... Dec 11 10:57:16 volumiokhv sudo[651]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Dec 11 10:57:16 volumiokhv sudo[651]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 11 10:57:16 volumiokhv sudo[651]: pam_unix(sudo:session): session closed for user root Dec 11 10:57:17 volumiokhv wireless.js[396]: trying... Dec 11 10:57:17 volumiokhv sudo[654]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Dec 11 10:57:17 volumiokhv sudo[654]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 11 10:57:17 volumiokhv sudo[654]: pam_unix(sudo:session): session closed for user root Dec 11 10:57:17 volumiokhv volumio-remote-updater[395]: [2021-12-11 10:57:17] [info] asio async_connect error: system:111 (Connection refused) Dec 11 10:57:17 volumiokhv volumio-remote-updater[395]: [2021-12-11 10:57:17] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Dec 11 10:57:17 volumiokhv volumio-remote-updater[395]: [2021-12-11 10:57:17] [error] handle_connect error: Underlying Transport Error Dec 11 10:57:18 volumiokhv wireless.js[396]: trying... Dec 11 10:57:18 volumiokhv sudo[657]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Dec 11 10:57:18 volumiokhv sudo[657]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 11 10:57:18 volumiokhv sudo[657]: pam_unix(sudo:session): session closed for user root Dec 11 10:57:19 volumiokhv wireless.js[396]: trying... Dec 11 10:57:19 volumiokhv sudo[660]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Dec 11 10:57:19 volumiokhv sudo[660]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 11 10:57:19 volumiokhv sudo[660]: pam_unix(sudo:session): session closed for user root Dec 11 10:57:20 volumiokhv dhcpcd[625]: wlan0: leased 192.168.1.65 for 43200 seconds Dec 11 10:57:20 volumiokhv dhcpcd[625]: wlan0: adding route to 192.168.1.0/24 Dec 11 10:57:20 volumiokhv dhcpcd[625]: wlan0: adding default route via 192.168.1.1 Dec 11 10:57:20 volumiokhv avahi-daemon[402]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.1.65. Dec 11 10:57:20 volumiokhv avahi-daemon[402]: New relevant interface wlan0.IPv4 for mDNS. Dec 11 10:57:20 volumiokhv avahi-daemon[402]: Registering new address record for 192.168.1.65 on wlan0.IPv4. Dec 11 10:57:20 volumiokhv wireless.js[396]: trying... Dec 11 10:57:20 volumiokhv sudo[684]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Dec 11 10:57:20 volumiokhv sudo[684]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 11 10:57:20 volumiokhv sudo[684]: pam_unix(sudo:session): session closed for user root Dec 11 10:57:20 volumiokhv wireless.js[396]: Connected to: ----icanhearyouhavingsex Dec 11 10:57:20 volumiokhv wireless.js[396]: ---- Dec 11 10:57:20 volumiokhv sudo[687]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 11 10:57:20 volumiokhv sudo[687]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 11 10:57:20 volumiokhv sudo[687]: pam_unix(sudo:session): session closed for user root Dec 11 10:57:20 volumiokhv wireless.js[396]: ... joined AP, wlan0 IPv4 is 192.168.1.65, ipV6 is undefined Dec 11 10:57:20 volumiokhv wireless.js[396]: It's done! AP Dec 11 10:57:20 volumiokhv systemd[1]: Started Wireless Services. Dec 11 10:57:20 volumiokhv systemd[1]: Started Volumio Backend Module. Dec 11 10:57:20 volumiokhv systemd[1]: Started Volumio Streaming Daemon. Dec 11 10:57:20 volumiokhv volumio-streaming-daemon[692]: ############################ Dec 11 10:57:20 volumiokhv volumio-streaming-daemon[692]: # Volumio Streaming Daemon # Dec 11 10:57:20 volumiokhv volumio-streaming-daemon[692]: # Running on port 7777 # Dec 11 10:57:20 volumiokhv volumio-streaming-daemon[692]: ############################ Dec 11 10:57:20 volumiokhv volumio-streaming-daemon[692]: Environment: production Dec 11 10:57:21 volumiokhv ntpd[546]: Listen normally on 3 wlan0 192.168.1.65:123 Dec 11 10:57:21 volumiokhv ntpd[546]: new interface(s) found: waking up resolver Dec 11 10:57:22 volumiokhv nmbd[526]: [2021/12/11 10:57:22.215818, 0] ../lib/util/become_daemon.c:138(daemon_ready) Dec 11 10:57:22 volumiokhv systemd[1]: Started Samba NMB Daemon. Dec 11 10:57:22 volumiokhv nmbd[526]: daemon_ready: STATUS=daemon 'nmbd' finished starting up and ready to serve connections Dec 11 10:57:22 volumiokhv systemd[1]: Starting Samba Winbind Daemon... Dec 11 10:57:22 volumiokhv winbindd[704]: [2021/12/11 10:57:22.792733, 0] ../source3/winbindd/winbindd_cache.c:3160(initialize_winbindd_cache) Dec 11 10:57:22 volumiokhv winbindd[704]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Dec 11 10:57:22 volumiokhv winbindd[704]: [2021/12/11 10:57:22.857471, 0] ../lib/util/become_daemon.c:138(daemon_ready) Dec 11 10:57:22 volumiokhv systemd[1]: Started Samba Winbind Daemon. Dec 11 10:57:22 volumiokhv winbindd[704]: daemon_ready: STATUS=daemon 'winbindd' finished starting up and ready to serve connections Dec 11 10:57:22 volumiokhv systemd[1]: Starting Samba SMB Daemon... Dec 11 10:57:23 volumiokhv winbindd[704]: [2021/12/11 10:57:23.731916, 0] ../source3/winbindd/wb_lookupsids.c:659(wb_lookupsids_recv) Dec 11 10:57:23 volumiokhv winbindd[704]: res_names->count = 3, expected 4 Dec 11 10:57:23 volumiokhv systemd[1]: Started Samba SMB Daemon. Dec 11 10:57:23 volumiokhv systemd[1]: Reached target Multi-User System. Dec 11 10:57:23 volumiokhv systemd[1]: Reached target Graphical Interface. Dec 11 10:57:23 volumiokhv smbd[709]: [2021/12/11 10:57:23.882091, 0] ../lib/util/become_daemon.c:138(daemon_ready) Dec 11 10:57:23 volumiokhv smbd[709]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections Dec 11 10:57:23 volumiokhv systemd[1]: Starting Update UTMP about System Runlevel Changes... Dec 11 10:57:23 volumiokhv systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Dec 11 10:57:23 volumiokhv systemd[1]: Started Update UTMP about System Runlevel Changes. Dec 11 10:57:23 volumiokhv systemd[1]: Startup finished in 11.831s (kernel) + 29.288s (userspace) = 41.120s. Dec 11 10:57:25 volumiokhv volumio[691]: info: ------------------------------------------- Dec 11 10:57:25 volumiokhv volumio[691]: info: ----- Volumio3 ---- Dec 11 10:57:25 volumiokhv volumio[691]: info: ------------------------------------------- Dec 11 10:57:25 volumiokhv volumio[691]: info: ----- System startup ---- Dec 11 10:57:25 volumiokhv volumio[691]: info: ------------------------------------------- Dec 11 10:57:27 volumiokhv volumio-remote-updater[395]: [2021-12-11 10:57:27] [connect] Successful connection Dec 11 10:57:27 volumiokhv volumio[691]: info: MYVOLUMIO Environment detected Dec 11 10:57:28 volumiokhv volumio[691]: info: Plugin folders cleanup Dec 11 10:57:28 volumiokhv volumio[691]: info: Scanning into folder /volumio/app/plugins/ Dec 11 10:57:28 volumiokhv volumio[691]: info: Scanning category audio_interface Dec 11 10:57:28 volumiokhv volumio[691]: info: Scanning category miscellanea Dec 11 10:57:28 volumiokhv volumio[691]: info: Scanning category music_service Dec 11 10:57:28 volumiokhv volumio[691]: info: Scanning category plugins.json Dec 11 10:57:28 volumiokhv volumio[691]: info: Scanning category system_controller Dec 11 10:57:28 volumiokhv volumio[691]: info: Scanning category user_interface Dec 11 10:57:28 volumiokhv volumio[691]: info: Scanning into folder /data/plugins/ Dec 11 10:57:28 volumiokhv volumio[691]: info: Scanning category music_service Dec 11 10:57:28 volumiokhv volumio[691]: info: Scanning category user_interface Dec 11 10:57:28 volumiokhv volumio[691]: info: Plugin folders cleanup completed Dec 11 10:57:28 volumiokhv volumio[691]: info: ------------------------------------------- Dec 11 10:57:28 volumiokhv volumio[691]: info: ----- Core plugins startup ---- Dec 11 10:57:28 volumiokhv volumio[691]: info: ------------------------------------------- Dec 11 10:57:28 volumiokhv volumio[691]: info: Loading plugins from folder /volumio/app/plugins/ Dec 11 10:57:28 volumiokhv volumio[691]: info: Adding plugin upnp to MyMusic Plugins Dec 11 10:57:28 volumiokhv volumio[691]: info: Adding plugin airplay_emulation to MyMusic Plugins Dec 11 10:57:28 volumiokhv volumio[691]: info: Adding plugin upnp_browser to MyMusic Plugins Dec 11 10:57:28 volumiokhv volumio[691]: info: Loading plugins from folder /data/plugins/ Dec 11 10:57:28 volumiokhv volumio[691]: info: Loading plugin "system"... Dec 11 10:57:28 volumiokhv volumio[691]: info: Loading plugin "appearance"... Dec 11 10:57:31 volumiokhv volumio[691]: info: Loading plugin "network"... Dec 11 10:57:31 volumiokhv volumio[691]: info: Refreshing Cached IP Addresses Dec 11 10:57:31 volumiokhv sudo[729]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 11 10:57:31 volumiokhv sudo[729]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 11 10:57:31 volumiokhv sudo[729]: pam_unix(sudo:session): session closed for user root Dec 11 10:57:31 volumiokhv volumio[691]: info: Loading plugin "services"... Dec 11 10:57:31 volumiokhv sudo[731]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 11 10:57:31 volumiokhv sudo[731]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 11 10:57:31 volumiokhv volumio[691]: info: Loading plugin "alsa_controller"... Dec 11 10:57:31 volumiokhv sudo[731]: pam_unix(sudo:session): session closed for user root Dec 11 10:57:31 volumiokhv sudo[734]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Dec 11 10:57:31 volumiokhv sudo[734]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 11 10:57:31 volumiokhv volumio[691]: info: Loading plugin "wizard"... Dec 11 10:57:31 volumiokhv volumio[691]: info: Loading plugin "volumio_command_line_client"... Dec 11 10:57:31 volumiokhv volumio[691]: info: Plugin upnp is not enabled Dec 11 10:57:31 volumiokhv volumio[691]: info: Loading plugin "my_music"... Dec 11 10:57:31 volumiokhv volumio[691]: info: Loading plugin "mpd"... Dec 11 10:57:31 volumiokhv systemd[1]: systemd-fsckd.service: Succeeded. Dec 11 10:57:32 volumiokhv volumio[691]: info: Creating MPD Configuration file Dec 11 10:57:32 volumiokhv volumio[691]: info: Plugin upnp_browser is not enabled Dec 11 10:57:32 volumiokhv volumio[691]: info: Loading plugin "networkfs"... Dec 11 10:57:32 volumiokhv sudo[742]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 11 10:57:32 volumiokhv sudo[742]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 11 10:57:32 volumiokhv sudo[744]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 11 10:57:32 volumiokhv sudo[744]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 11 10:57:32 volumiokhv sudo[742]: pam_unix(sudo:session): session closed for user root Dec 11 10:57:32 volumiokhv systemd[1]: Stopping Music Player Daemon... Dec 11 10:57:32 volumiokhv systemd[1]: mpd.service: Succeeded. Dec 11 10:57:32 volumiokhv systemd[1]: Stopped Music Player Daemon. Dec 11 10:57:32 volumiokhv systemd[1]: Starting Music Player Daemon... Dec 11 10:57:32 volumiokhv sudo[734]: pam_unix(sudo:session): session closed for user root Dec 11 10:57:33 volumiokhv sh[322]: timed out Dec 11 10:57:33 volumiokhv dhcpcd[369]: timed out Dec 11 10:57:33 volumiokhv sh[322]: dhcpcd exited Dec 11 10:57:33 volumiokhv dhcpcd[369]: dhcpcd exited Dec 11 10:57:33 volumiokhv sh[322]: ifup: failed to bring up eth0 Dec 11 10:57:33 volumiokhv systemd[1]: ifup@eth0.service: Main process exited, code=exited, status=1/FAILURE Dec 11 10:57:33 volumiokhv systemd[1]: ifup@eth0.service: Failed with result 'exit-code'. Dec 11 10:57:33 volumiokhv sudo[753]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=,password=,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.1.15/musicshare /mnt/NAS/Musik Dec 11 10:57:33 volumiokhv sudo[753]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 11 10:57:33 volumiokhv volumio[691]: info: Starting Udev Watcher for removable devices Dec 11 10:57:33 volumiokhv sudo[755]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=,password=,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.1.15/musicshare_new /mnt/NAS/Musik_Neu Dec 11 10:57:33 volumiokhv sudo[755]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 11 10:57:33 volumiokhv sudo[758]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=,password=,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.1.15/musicshare_hires /mnt/NAS/Musik_HiRes Dec 11 10:57:33 volumiokhv sudo[758]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 11 10:57:33 volumiokhv kernel: FS-Cache: Netfs 'cifs' registered for caching Dec 11 10:57:33 volumiokhv kernel: Key type cifs.spnego registered Dec 11 10:57:33 volumiokhv kernel: Key type cifs.idmap registered Dec 11 10:57:33 volumiokhv kernel: CIFS: Attempting to mount //192.168.1.15/musicshare_new Dec 11 10:57:33 volumiokhv 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. Dec 11 10:57:33 volumiokhv 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. Dec 11 10:57:33 volumiokhv kernel: CIFS: Attempting to mount //192.168.1.15/musicshare Dec 11 10:57:33 volumiokhv 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. Dec 11 10:57:33 volumiokhv kernel: FS-Cache: Duplicate cookie detected Dec 11 10:57:33 volumiokhv kernel: FS-Cache: O-cookie c=a5f4c379 [p=62594165 fl=222 nc=0 na=1] Dec 11 10:57:33 volumiokhv kernel: FS-Cache: O-cookie d=0a6c831a n=5426a7d5 Dec 11 10:57:33 volumiokhv kernel: FS-Cache: O-key=[8] '020001bdc0a8010f' Dec 11 10:57:33 volumiokhv kernel: FS-Cache: N-cookie c=ec59dc09 [p=62594165 fl=2 nc=0 na=1] Dec 11 10:57:33 volumiokhv kernel: FS-Cache: N-cookie d=0a6c831a n=a465a175 Dec 11 10:57:33 volumiokhv kernel: FS-Cache: N-key=[8] '020001bdc0a8010f' Dec 11 10:57:33 volumiokhv kernel: FS-Cache: Duplicate cookie detected Dec 11 10:57:33 volumiokhv kernel: FS-Cache: O-cookie c=a5f4c379 [p=62594165 fl=222 nc=0 na=1] Dec 11 10:57:33 volumiokhv kernel: FS-Cache: O-cookie d=0a6c831a n=5426a7d5 Dec 11 10:57:33 volumiokhv kernel: FS-Cache: O-key=[8] '020001bdc0a8010f' Dec 11 10:57:33 volumiokhv kernel: FS-Cache: N-cookie c=ec59dc09 [p=62594165 fl=2 nc=0 na=1] Dec 11 10:57:33 volumiokhv kernel: FS-Cache: N-cookie d=0a6c831a n=88b15414 Dec 11 10:57:33 volumiokhv kernel: FS-Cache: N-key=[8] '020001bdc0a8010f' Dec 11 10:57:33 volumiokhv sudo[758]: pam_unix(sudo:session): session closed for user root Dec 11 10:57:33 volumiokhv sudo[753]: pam_unix(sudo:session): session closed for user root Dec 11 10:57:33 volumiokhv sudo[755]: pam_unix(sudo:session): session closed for user root Dec 11 10:57:34 volumiokhv volumio[691]: info: Ignoring mount for partition: boot Dec 11 10:57:34 volumiokhv volumio[691]: info: Ignoring mount for partition: volumio Dec 11 10:57:34 volumiokhv volumio[691]: info: Ignoring mount for partition: volumio_data Dec 11 10:57:34 volumiokhv volumio[691]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 11 10:57:34 volumiokhv volumio[691]: info: Loading plugin "alarm-clock"... Dec 11 10:57:34 volumiokhv volumio[691]: info: Plugin airplay_emulation is not enabled Dec 11 10:57:34 volumiokhv volumio[691]: info: Loading plugin "last_100"... Dec 11 10:57:34 volumiokhv volumio[691]: info: Loading plugin "webradio"... Dec 11 10:57:35 volumiokhv volumio[691]: info: Loading plugin "i2s_dacs"... Dec 11 10:57:35 volumiokhv volumio[691]: info: Loading plugin "volumiodiscovery"... Dec 11 10:57:35 volumiokhv volumio[691]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 11 10:57:35 volumiokhv volumio[691]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 11 10:57:35 volumiokhv volumio[691]: *** WARNING *** For more information see Dec 11 10:57:35 volumiokhv node[691]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 11 10:57:35 volumiokhv volumio[691]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 11 10:57:35 volumiokhv volumio[691]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 11 10:57:35 volumiokhv volumio[691]: *** WARNING *** For more information see Dec 11 10:57:35 volumiokhv node[691]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 11 10:57:35 volumiokhv node[691]: *** WARNING *** For more information see Dec 11 10:57:35 volumiokhv node[691]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 11 10:57:35 volumiokhv node[691]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 11 10:57:35 volumiokhv node[691]: *** WARNING *** For more information see Dec 11 10:57:35 volumiokhv volumio[691]: info: Applying required configuration parameters for plugin volumiodiscovery Dec 11 10:57:35 volumiokhv volumio[691]: info: Discovery: Started advertising with name: VolumioKHV Dec 11 10:57:35 volumiokhv volumio[691]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 11 10:57:35 volumiokhv volumio[691]: info: Loading plugin "now_playing"... Dec 11 10:57:35 volumiokhv mpd[751]: Dec 11 10:57 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 11 10:57:36 volumiokhv mpd[751]: Dec 11 10:57 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Dec 11 10:57:36 volumiokhv mpd[751]: Dec 11 10:57 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Dec 11 10:57:36 volumiokhv systemd[1]: Started Music Player Daemon. Dec 11 10:57:36 volumiokhv sudo[744]: pam_unix(sudo:session): session closed for user root Dec 11 10:57:36 volumiokhv volumio[691]: info: Loading plugin "outputs"... Dec 11 10:57:36 volumiokhv volumio[691]: info: Loading plugin "albumart"... Dec 11 10:57:36 volumiokhv volumio[691]: info: Plugin example_plugin is not enabled Dec 11 10:57:36 volumiokhv volumio[691]: info: Loading plugin "inputs"... Dec 11 10:57:36 volumiokhv volumio[691]: info: Loading plugin "updater_comm"... Dec 11 10:57:37 volumiokhv volumio[691]: info: Plugin mpdemulation is not enabled Dec 11 10:57:37 volumiokhv volumio[691]: info: Loading plugin "rest_api"... Dec 11 10:57:37 volumiokhv volumio[691]: info: Loading plugin "websocket"... Dec 11 10:57:37 volumiokhv volumio[691]: info: Loading plugin "spop"... Dec 11 10:57:37 volumiokhv volumio[691]: Forking 3 albumart workers Dec 11 10:57:39 volumiokhv volumio[691]: Starting albumart workers Dec 11 10:57:39 volumiokhv volumio[691]: Starting albumart workers Dec 11 10:57:39 volumiokhv volumio[691]: Starting albumart workers Dec 11 10:57:39 volumiokhv volumio[691]: info: Loading plugin "volspotconnect2"... Dec 11 10:57:41 volumiokhv volumio[691]: info: Loading plugin "Systeminfo"... Dec 11 10:57:42 volumiokhv volumio[691]: info: Loading i18n strings for locale en Dec 11 10:57:42 volumiokhv volumio[691]: Updating browse sources language Dec 11 10:57:42 volumiokhv volumio[691]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 11 10:57:42 volumiokhv volumio[691]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 11 10:57:42 volumiokhv volumio[691]: info: CoreCommandRouter::initPlayerControls Dec 11 10:57:42 volumiokhv volumio[691]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 10:57:42 volumiokhv volumio[691]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 10:57:42 volumiokhv volumio-remote-updater[395]: [2021-12-11 10:57:42] [connect] Successful connection Dec 11 10:57:42 volumiokhv volumio[691]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 10:57:42 volumiokhv volumio[691]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 11 10:57:42 volumiokhv volumio[691]: Express server listening on port 3000 Dec 11 10:57:42 volumiokhv volumio[691]: [Metrics] WebUI: 18s 342.29ms Dec 11 10:57:42 volumiokhv volumio[691]: info: CoreStateMachine::resetVolumioState Dec 11 10:57:42 volumiokhv volumio[691]: info: CoreStateMachine::getcurrentVolume Dec 11 10:57:42 volumiokhv volumio[691]: info: CoreCommandRouter::volumioRetrievevolume Dec 11 10:57:42 volumiokhv volumio[691]: info: MPD Permissions set Dec 11 10:57:42 volumiokhv volumio[691]: verbose: New Socket.io Connection to 192.168.1.65:3000 from 192.168.1.234 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/96.0.4664.51 Safari/537.36 Total Clients: 1 Dec 11 10:57:42 volumiokhv volumio[691]: verbose: New Socket.io Connection to 192.168.1.65:3000 from 192.168.1.234 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/96.0.4664.51 Safari/537.36 Total Clients: 2 Dec 11 10:57:42 volumiokhv volumio[691]: verbose: New Socket.io Connection to 192.168.1.65:3000 from 192.168.1.192 UA: node-XMLHttpRequest Total Clients: 3 Dec 11 10:57:42 volumiokhv volumio[691]: verbose: New Socket.io Connection to 192.168.1.65:3000 from 192.168.1.234 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/96.0.4664.51 Safari/537.36 Total Clients: 4 Dec 11 10:57:42 volumiokhv volumio[691]: verbose: New Socket.io Connection to 192.168.1.65 from 192.168.1.234 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/96.0.4664.51 Safari/537.36 Total Clients: 5 Dec 11 10:57:42 volumiokhv volumio[691]: verbose: New Socket.io Connection to 192.168.1.65 from 192.168.1.234 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/96.0.4664.51 Safari/537.36 Total Clients: 6 Dec 11 10:57:42 volumiokhv volumio-remote-updater[395]: [2021-12-11 10:57:42] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1639220262 101 Dec 11 10:57:42 volumiokhv volumio[691]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Total Clients: 7 Dec 11 10:57:43 volumiokhv volumio[691]: verbose: New Socket.io Connection to 192.168.1.65 from 192.168.1.234 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/96.0.4664.51 Safari/537.36 Total Clients: 8 Dec 11 10:57:43 volumiokhv volumio[691]: verbose: New Socket.io Connection to 192.168.1.65 from 192.168.1.234 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/96.0.4664.51 Safari/537.36 Total Clients: 9 Dec 11 10:57:43 volumiokhv volumio[691]: info: MPD running with PID751 Dec 11 10:57:43 volumiokhv volumio[691]: ,establishing connection Dec 11 10:57:43 volumiokhv volumio[691]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 10:57:43 volumiokhv volumio[691]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 10:57:43 volumiokhv volumio[691]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 10:57:43 volumiokhv volumio[691]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 10:57:43 volumiokhv volumio[691]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 10:57:43 volumiokhv volumio[691]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 10:57:43 volumiokhv volumio[691]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 10:57:43 volumiokhv volumio[691]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 10:57:43 volumiokhv volumio[691]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 11 10:57:43 volumiokhv volumio[691]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 10:57:43 volumiokhv volumio[691]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 11 10:57:43 volumiokhv volumio[691]: info: Executing start script for DAC IQaudIO DAC Plus Dec 11 10:57:43 volumiokhv volumio[691]: info: Reloading queue from file Dec 11 10:57:43 volumiokhv volumio[691]: info: CALLMETHOD: user_interface now_playing broadcastPluginInfo undefined Dec 11 10:57:43 volumiokhv volumio[691]: info: CoreCommandRouter::executeOnPlugin: now_playing , broadcastPluginInfo Dec 11 10:57:43 volumiokhv volumio[691]: verbose: New Socket.io Connection to 192.168.1.65 from 192.168.1.234 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/96.0.4664.51 Safari/537.36 Total Clients: 10 Dec 11 10:57:43 volumiokhv volumio[691]: verbose: New Socket.io Connection to 192.168.1.65 from 192.168.1.234 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/96.0.4664.51 Safari/537.36 Total Clients: 11 Dec 11 10:57:43 volumiokhv volumio[691]: error: updateQueue error: null Dec 11 10:57:43 volumiokhv volumio[691]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Dec 11 10:57:43 volumiokhv volumio[691]: info: VolumeController:: Volume=20 Mute =false Dec 11 10:57:43 volumiokhv volumio[691]: info: CoreStateMachine::pushState Dec 11 10:57:43 volumiokhv volumio[691]: info: CorePlayQueue::getTrack 0 Dec 11 10:57:43 volumiokhv volumio[691]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 11 10:57:43 volumiokhv volumio[691]: info: CoreCommandRouter::volumioPushState Dec 11 10:57:43 volumiokhv volumio[691]: info: CoreStateMachine::updateTrackBlock Dec 11 10:57:43 volumiokhv volumio[691]: info: CorePlayQueue::getTrackBlock Dec 11 10:57:43 volumiokhv volumio[691]: info: CoreCommandRouter::volumioRetrievevolume Dec 11 10:57:43 volumiokhv volumio[691]: info: CoreStateMachine::setRepeat true single undefined Dec 11 10:57:43 volumiokhv volumio[691]: info: CoreStateMachine::pushState Dec 11 10:57:43 volumiokhv volumio[691]: info: CorePlayQueue::getTrack 0 Dec 11 10:57:43 volumiokhv volumio[691]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 11 10:57:43 volumiokhv volumio[691]: info: CoreCommandRouter::volumioPushState Dec 11 10:57:43 volumiokhv volumio[691]: info: CoreStateMachine::setRandom null Dec 11 10:57:43 volumiokhv volumio[691]: info: CoreStateMachine::pushState Dec 11 10:57:43 volumiokhv volumio[691]: info: CorePlayQueue::getTrack 0 Dec 11 10:57:43 volumiokhv volumio[691]: info: CoreCommandRouter::volumioPushState Dec 11 10:57:43 volumiokhv volumio[691]: info: Setting Device type: Raspberry PI Dec 11 10:57:43 volumiokhv volumio[691]: verbose: New Socket.io Connection to 192.168.1.65 from 192.168.1.234 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/96.0.4664.51 Safari/537.36 Total Clients: 12 Dec 11 10:57:43 volumiokhv volumio[691]: verbose: New Socket.io Connection to 192.168.1.65 from 192.168.1.234 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/96.0.4664.51 Safari/537.36 Total Clients: 13 Dec 11 10:57:43 volumiokhv volumio[691]: info: DAC script executed Dec 11 10:57:43 volumiokhv volumio[691]: info: Completed loading Core Plugins Dec 11 10:57:43 volumiokhv volumio[691]: info: Preparing to generate the ALSA configuration file Dec 11 10:57:43 volumiokhv volumio[691]: verbose: New Socket.io Connection to 192.168.1.65 from 192.168.1.234 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/96.0.4664.51 Safari/537.36 Total Clients: 14 Dec 11 10:57:43 volumiokhv volumio[691]: verbose: New Socket.io Connection to 192.168.1.65 from 192.168.1.234 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/96.0.4664.51 Safari/537.36 Total Clients: 15 Dec 11 10:57:43 volumiokhv volumio[691]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , initSocket Dec 11 10:57:43 volumiokhv volumio[691]: verbose: New Socket.io Connection to 192.168.1.65 from 192.168.1.234 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/96.0.4664.51 Safari/537.36 Total Clients: 16 Dec 11 10:57:43 volumiokhv volumio[691]: verbose: New Socket.io Connection to 192.168.1.65 from 192.168.1.234 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/96.0.4664.51 Safari/537.36 Total Clients: 17 Dec 11 10:57:43 volumiokhv volumio[691]: info: CoreCommandRouter::volumioGetState Dec 11 10:57:43 volumiokhv volumio[691]: info: CorePlayQueue::getTrack 0 Dec 11 10:57:43 volumiokhv volumio[691]: info: Discovery: adding 8a069d7d-ee95-4d83-b63c-97f3e3b74d8b Dec 11 10:57:43 volumiokhv volumio[691]: info: mDNS: Found device VolumioKHV Dec 11 10:57:43 volumiokhv volumio[691]: info: CoreCommandRouter::volumioGetState Dec 11 10:57:43 volumiokhv volumio[691]: info: CorePlayQueue::getTrack 0 Dec 11 10:57:44 volumiokhv volumio[691]: verbose: New Socket.io Connection to 192.168.1.65 from 192.168.1.234 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/96.0.4664.51 Safari/537.36 Total Clients: 18 Dec 11 10:57:44 volumiokhv volumio[691]: verbose: New Socket.io Connection to 192.168.1.65 from 192.168.1.234 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/96.0.4664.51 Safari/537.36 Total Clients: 19 Dec 11 10:57:44 volumiokhv volumio[691]: info: VolumeController:: Volume=20 Mute =false Dec 11 10:57:44 volumiokhv volumio[691]: info: CoreStateMachine::pushState Dec 11 10:57:44 volumiokhv volumio[691]: info: CorePlayQueue::getTrack 0 Dec 11 10:57:44 volumiokhv volumio[691]: info: CoreCommandRouter::volumioPushState Dec 11 10:57:44 volumiokhv volumio[691]: info: Asound.conf file unchanged, so no further update is needed Dec 11 10:57:44 volumiokhv volumio[691]: info: Output device has changed, restarting MPD Dec 11 10:57:44 volumiokhv volumio[691]: info: ___________ START PLUGINS ___________ Dec 11 10:57:44 volumiokhv sudo[898]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 11 10:57:44 volumiokhv sudo[898]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 11 10:57:44 volumiokhv volumio[691]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 11 10:57:44 volumiokhv volumio[691]: info: [1639220264174] CoreMusicLibrary::Adding element Last_100 Dec 11 10:57:44 volumiokhv volumio[691]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 11 10:57:44 volumiokhv sudo[898]: pam_unix(sudo:session): session closed for user root Dec 11 10:57:44 volumiokhv sudo[900]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 11 10:57:44 volumiokhv sudo[900]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 11 10:57:44 volumiokhv volumio[691]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 11 10:57:44 volumiokhv volumio[691]: info: [1639220264196] CoreMusicLibrary::Adding element Webradio Dec 11 10:57:44 volumiokhv volumio[691]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 11 10:57:44 volumiokhv volumio[691]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 11 10:57:44 volumiokhv systemd[1]: Stopping Music Player Daemon... Dec 11 10:57:44 volumiokhv volumio[691]: [SpotifyConnect] Creating VLS config file Dec 11 10:57:44 volumiokhv volumio[691]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 10:57:44 volumiokhv volumio[691]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 10:57:44 volumiokhv volumio[691]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 10:57:44 volumiokhv volumio[691]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 10:57:44 volumiokhv volumio[691]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 10:57:44 volumiokhv volumio[691]: [SpotifyConnect] Starting metadata listener Dec 11 10:57:44 volumiokhv sudo[904]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start spop.service Dec 11 10:57:44 volumiokhv sudo[904]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 11 10:57:44 volumiokhv systemd[1]: mpd.service: Succeeded. Dec 11 10:57:44 volumiokhv systemd[1]: Stopped Music Player Daemon. Dec 11 10:57:44 volumiokhv systemd[1]: Starting Music Player Daemon... Dec 11 10:57:44 volumiokhv volumio[691]: info: Volumio Calling Home Dec 11 10:57:44 volumiokhv systemd[1]: Configuration file /lib/systemd/system/spop.service is marked executable. Please remove executable permission bits. Proceeding anyway. Dec 11 10:57:44 volumiokhv systemd[1]: Configuration file /lib/systemd/system/spop.service is marked world-writable. Please remove world writability permission bits. Proceeding anyway. Dec 11 10:57:44 volumiokhv systemd[1]: Started Spotify Daemon. Dec 11 10:57:44 volumiokhv sudo[907]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volspotconnect2.service Dec 11 10:57:44 volumiokhv sudo[907]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 11 10:57:44 volumiokhv sudo[904]: pam_unix(sudo:session): session closed for user root Dec 11 10:57:44 volumiokhv systemd[1]: Started Volspotconnect2 Daemon. Dec 11 10:57:44 volumiokhv sudo[907]: pam_unix(sudo:session): session closed for user root Dec 11 10:57:44 volumiokhv volumio[691]: info: [now-playing-app] App is listening on port 4004. Dec 11 10:57:44 volumiokhv volumio[910]: 2021-12-11 10:57:44 [SPTF] 10:57:44.750 I [offline_authorizer.cpp:297] Unable to login offline: no such user Dec 11 10:57:44 volumiokhv volumio[910]: 2021-12-11 10:57:44 [SPTF] 10:57:44.764 I [ap:1752] Connecting to AP ap.spotify.com:4070 Dec 11 10:57:44 volumiokhv volumio[913]: vollibrespot v0.2.3 769ce77 2021-06-04 (librespot 08d8bcc 2020-10-07) -- Built On 2021-06-04 Dec 11 10:57:44 volumiokhv volumio[913]: Reading Config from "volspotify.toml" Dec 11 10:57:44 volumiokhv volumio[910]: 2021-12-11 10:57:44 [SPTF] 10:57:44.813 I [ap:1226] Connected to AP: 35.190.242.24:4070 Dec 11 10:57:44 volumiokhv volumio[913]: [Vollibrespot] : Using Alsa backend with device: volumio Dec 11 10:57:44 volumiokhv volumio[691]: info: Discovery: adding 2c95a9e3-40a5-434d-87bd-3a9df1bc8ede Dec 11 10:57:44 volumiokhv volumio[691]: info: mDNS: Found device Volumio Dec 11 10:57:44 volumiokhv volumio[691]: verbose: New Socket.io Connection to 192.168.1.65 from 192.168.1.234 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/96.0.4664.51 Safari/537.36 Total Clients: 20 Dec 11 10:57:44 volumiokhv volumio[691]: verbose: New Socket.io Connection to 192.168.1.65 from 192.168.1.234 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/96.0.4664.51 Safari/537.36 Total Clients: 21 Dec 11 10:57:44 volumiokhv volumio[691]: verbose: New Socket.io Connection to 192.168.1.65 from 192.168.1.234 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/96.0.4664.51 Safari/537.36 Total Clients: 22 Dec 11 10:57:45 volumiokhv volumio[913]: [Vollibrespot] : Failed to register IPv6 receiver: Os { code: 19, kind: Other, message: "No such device" } Dec 11 10:57:45 volumiokhv volumio[691]: error: MPD error: Error: write EPIPE Dec 11 10:57:45 volumiokhv volumio[691]: verbose: New Socket.io Connection to 192.168.1.65 from 192.168.1.234 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/96.0.4664.51 Safari/537.36 Total Clients: 23 Dec 11 10:57:45 volumiokhv volumio[691]: verbose: New Socket.io Connection to 192.168.1.65 from 192.168.1.234 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/96.0.4664.51 Safari/537.36 Total Clients: 24 Dec 11 10:57:45 volumiokhv volumio[691]: [SpotifyConnect] Vollibrespot Daemon service started! Dec 11 10:57:45 volumiokhv volumio[691]: [Metrics] SpotifyConnect: 0s 818.87ms Dec 11 10:57:45 volumiokhv volumio[691]: info: SpopD Daemon Started Dec 11 10:57:45 volumiokhv volumio[691]: info: MPD Permissions set Dec 11 10:57:45 volumiokhv volumio[691]: info: Volumio called home Dec 11 10:57:45 volumiokhv volumio[691]: info: CoreCommandRouter::volumioGetState Dec 11 10:57:45 volumiokhv volumio[691]: info: CorePlayQueue::getTrack 0 Dec 11 10:57:45 volumiokhv volumio[691]: verbose: New Socket.io Connection to 192.168.1.65 from 192.168.1.234 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/96.0.4664.51 Safari/537.36 Total Clients: 25 Dec 11 10:57:45 volumiokhv volumio[691]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 26 Dec 11 10:57:45 volumiokhv volumio[691]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 10:57:45 volumiokhv volumio[691]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 10:57:45 volumiokhv volumio[691]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 10:57:45 volumiokhv volumio[691]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 10:57:45 volumiokhv volumio[691]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 10:57:45 volumiokhv volumio[691]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 10:57:45 volumiokhv volumio[691]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 10:57:45 volumiokhv volumio[691]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 10:57:45 volumiokhv volumio[691]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 11 10:57:45 volumiokhv volumio[691]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 10:57:45 volumiokhv volumio[691]: info: CoreCommandRouter::volumioGetQueue Dec 11 10:57:45 volumiokhv volumio[691]: info: CoreStateMachine::getQueue Dec 11 10:57:45 volumiokhv volumio[691]: info: CorePlayQueue::getQueue Dec 11 10:57:45 volumiokhv volumio[691]: info: CoreCommandRouter::executeOnPlugin: system , getHwuuid Dec 11 10:57:45 volumiokhv volumio[691]: info: CoreCommandRouter::volumioGetVisibleSources Dec 11 10:57:45 volumiokhv volumio[691]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 11 10:57:45 volumiokhv volumio[691]: info: CoreCommandRouter::volumioGetState Dec 11 10:57:45 volumiokhv volumio[691]: info: CorePlayQueue::getTrack 0 Dec 11 10:57:45 volumiokhv volumio[691]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 11 10:57:45 volumiokhv volumio[691]: info: Listing playlists Dec 11 10:57:45 volumiokhv volumio[691]: info: CoreCommandRouter::volumioGetVisibleSources Dec 11 10:57:45 volumiokhv volumio[691]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 11 10:57:45 volumiokhv volumio[691]: info: CoreCommandRouter::volumioGetState Dec 11 10:57:45 volumiokhv volumio[691]: info: CorePlayQueue::getTrack 0 Dec 11 10:57:45 volumiokhv volumio[691]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 11 10:57:45 volumiokhv volumio[691]: info: Listing playlists Dec 11 10:57:46 volumiokhv volumio[691]: info: CoreCommandRouter::volumioGetState Dec 11 10:57:46 volumiokhv volumio[691]: info: CorePlayQueue::getTrack 0 Dec 11 10:57:46 volumiokhv volumio[910]: 2021-12-11 10:57:46 [SPTF] 10:57:46.636 I [offline-mgr:2030] 0 files are locked. 0 images are locked Dec 11 10:57:46 volumiokhv volumio[910]: 2021-12-11 10:57:46 [SPTF] 10:57:46.637 I [offline-mgr:2056] 0 files unlocked. 0 images unlocked Dec 11 10:57:46 volumiokhv mpd[920]: Dec 11 10:57 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 11 10:57:47 volumiokhv mpd[920]: Dec 11 10:57 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Dec 11 10:57:47 volumiokhv mpd[920]: Dec 11 10:57 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Dec 11 10:57:47 volumiokhv systemd[1]: Started Music Player Daemon. Dec 11 10:57:47 volumiokhv sudo[900]: pam_unix(sudo:session): session closed for user root Dec 11 10:57:47 volumiokhv volumio[691]: error: updateQueue error: null Dec 11 10:57:50 volumiokhv volumio[691]: error: updateQueue error: null Dec 11 10:57:50 volumiokhv volumio[691]: info: Connecting to daemon Dec 11 10:57:50 volumiokhv volumio[691]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 11 10:57:50 volumiokhv volumio[691]: info: [1639220270148] CoreMusicLibrary::Adding element Spotify Dec 11 10:57:50 volumiokhv volumio[691]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 11 10:57:50 volumiokhv volumio[691]: Cannot find translation for sourceSpotify Dec 11 10:57:50 volumiokhv volumio[691]: info: Completed starting Core Plugins Dec 11 10:57:50 volumiokhv volumio[691]: info: ------------------------------------------- Dec 11 10:57:50 volumiokhv volumio[691]: info: ----- MyVolumio plugins startup ---- Dec 11 10:57:50 volumiokhv volumio[691]: info: ------------------------------------------- Dec 11 10:57:50 volumiokhv volumio[691]: info: [MyVolumio PluginManager] Fetching plans data.... Dec 11 10:57:50 volumiokhv volumio[691]: info: FIRST BRANCH Dec 11 10:57:50 volumiokhv volumio[691]: info: An error occurred while listing Spotify new albums WebapiError: Unauthorized Dec 11 10:57:50 volumiokhv volumio[691]: info: An error occurred while listing Spotify featured playlists WebapiError: Unauthorized Dec 11 10:57:50 volumiokhv volumio[691]: info: An error occurred while listing Spotify categories WebapiError: Unauthorized Dec 11 10:57:50 volumiokhv volumio[691]: info: New Spotify access token = BQDZquCTqYp9aEs85QdhEDhWP_kd63dqvmaw6MDUGh_DVjFM0LgDuzLJS1tngEukgJciBqn40xJ106ddt0gaDibuq3VqHBdEL1J4pMwKibT_G_OdZ8PIKJVbtA5oDS3ZtfGn9xz7sVr1k-YK6WdG15DJzWlFK6N9nwC3PABYbhnffMNs2361FJObXDO3OfSzOhiD8Yne5TOGWZ5U1ZAw Dec 11 10:57:50 volumiokhv volumio[691]: info: Spotify credentials grant success - running version from March 24, 2019 Dec 11 10:57:56 volumiokhv volumio[691]: verbose: New Socket.io Connection to 192.168.1.65 from 192.168.1.234 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/96.0.4664.51 Safari/537.36 Total Clients: 26 Dec 11 10:57:56 volumiokhv volumio[691]: info: CoreCommandRouter::volumioGetState Dec 11 10:57:56 volumiokhv volumio[691]: info: CorePlayQueue::getTrack 0 Dec 11 10:57:56 volumiokhv volumio[691]: info: CoreCommandRouter::volumioGetQueue Dec 11 10:57:56 volumiokhv volumio[691]: info: CoreStateMachine::getQueue Dec 11 10:57:56 volumiokhv volumio[691]: info: CorePlayQueue::getQueue Dec 11 10:57:56 volumiokhv volumio[691]: info: CoreCommandRouter::executeOnPlugin: system , getHwuuid Dec 11 10:57:58 volumiokhv volumio[691]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Dec 11 10:57:58 volumiokhv volumio[691]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Dec 11 10:57:58 volumiokhv volumio[691]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Dec 11 10:57:58 volumiokhv volumio[691]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Dec 11 10:57:58 volumiokhv volumio[691]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Dec 11 10:57:58 volumiokhv volumio[691]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Dec 11 10:57:58 volumiokhv volumio[691]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Dec 11 10:57:58 volumiokhv volumio[691]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Dec 11 10:57:58 volumiokhv volumio[691]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Dec 11 10:57:58 volumiokhv volumio[691]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Dec 11 10:57:58 volumiokhv volumio[691]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Dec 11 10:57:58 volumiokhv volumio[691]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Dec 11 10:57:58 volumiokhv volumio[691]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Dec 11 10:57:58 volumiokhv volumio[691]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Dec 11 10:57:58 volumiokhv volumio[691]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Dec 11 10:57:58 volumiokhv volumio[691]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Dec 11 10:57:58 volumiokhv volumio[691]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Dec 11 10:57:58 volumiokhv volumio[691]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Dec 11 10:57:58 volumiokhv volumio[691]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Dec 11 10:57:58 volumiokhv volumio[691]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Dec 11 10:57:58 volumiokhv volumio[691]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Dec 11 10:57:58 volumiokhv volumio[691]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Dec 11 10:57:58 volumiokhv volumio[691]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Dec 11 10:57:58 volumiokhv volumio[691]: info: Adding plugin bluetooth to MyMusic Plugins Dec 11 10:57:58 volumiokhv volumio[691]: info: Adding plugin multiroom to MyMusic Plugins Dec 11 10:57:58 volumiokhv volumio[691]: info: Adding plugin cd_controller to MyMusic Plugins Dec 11 10:57:58 volumiokhv volumio[691]: info: Adding plugin smart_inputs to MyMusic Plugins Dec 11 10:57:58 volumiokhv volumio[691]: info: Adding plugin tidalconnect to MyMusic Plugins Dec 11 10:57:58 volumiokhv volumio[691]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Dec 11 10:58:01 volumiokhv volumio[691]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Dec 11 10:58:01 volumiokhv volumio[691]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Dec 11 10:58:01 volumiokhv volumio[691]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 11 10:58:01 volumiokhv volumio[691]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 11 10:58:01 volumiokhv volumio[691]: info: Starting MyVolumio Remote Streaming Endpoints Dec 11 10:58:01 volumiokhv volumio[691]: info: MyVolumio login type: Token Dec 11 10:58:01 volumiokhv volumio[691]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Dec 11 10:58:01 volumiokhv volumio[691]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Dec 11 10:58:01 volumiokhv volumio[691]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Dec 11 10:58:01 volumiokhv volumio[691]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Dec 11 10:58:01 volumiokhv volumio[691]: info: Streaming services startup Dec 11 10:58:01 volumiokhv volumio[691]: info: Starting Streaming Daemon Dec 11 10:58:01 volumiokhv sudo[930]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Dec 11 10:58:01 volumiokhv sudo[930]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 11 10:58:01 volumiokhv systemd[1]: Stopping Volumio Streaming Daemon... Dec 11 10:58:01 volumiokhv systemd[1]: volumio-streaming-daemon.service: Main process exited, code=killed, status=15/TERM Dec 11 10:58:01 volumiokhv systemd[1]: volumio-streaming-daemon.service: Succeeded. Dec 11 10:58:01 volumiokhv systemd[1]: Stopped Volumio Streaming Daemon. Dec 11 10:58:01 volumiokhv systemd[1]: Started Volumio Streaming Daemon. Dec 11 10:58:01 volumiokhv sudo[930]: pam_unix(sudo:session): session closed for user root Dec 11 10:58:01 volumiokhv volumio-streaming-daemon[936]: ############################ Dec 11 10:58:01 volumiokhv volumio-streaming-daemon[936]: # Volumio Streaming Daemon # Dec 11 10:58:01 volumiokhv volumio-streaming-daemon[936]: # Running on port 7777 # Dec 11 10:58:01 volumiokhv volumio-streaming-daemon[936]: ############################ Dec 11 10:58:01 volumiokhv volumio-streaming-daemon[936]: Environment: production Dec 11 10:58:02 volumiokhv volumio[691]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Dec 11 10:58:02 volumiokhv volumio[691]: info: MYVOLUMIO: Adding device Dec 11 10:58:02 volumiokhv volumio[691]: info: MYVOLUMIO: Evaluating Server Dec 11 10:58:03 volumiokhv volumio[691]: info: [MyVolumio PluginManager] Subscribed plan changed to virtuoso Dec 11 10:58:03 volumiokhv volumio[691]: info: Removing browser output: myVolumio user plan is not superstar Dec 11 10:58:03 volumiokhv volumio[691]: info: Removing audio output: Dec 11 10:58:03 volumiokhv volumio[691]: info: MYVOLUMIO: Adding device Dec 11 10:58:03 volumiokhv volumio[691]: info: MYVOLUMIO: Evaluating Server Dec 11 10:58:03 volumiokhv volumio[691]: info: Remote config written successfully Dec 11 10:58:03 volumiokhv volumio[691]: info: Starting Tunnel 1 Dec 11 10:58:03 volumiokhv volumio[691]: info: Starting Tunnel Connection Checker Dec 11 10:58:03 volumiokhv volumio[691]: info: Completed starting MyVolumio Plugin Dec 11 10:58:03 volumiokhv sudo[957]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart sshtunnel.service Dec 11 10:58:03 volumiokhv sudo[957]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 11 10:58:03 volumiokhv systemd[1]: Started MyVolumio SSH Tunnel. Dec 11 10:58:03 volumiokhv volumio[691]: info: Setting Geolocation for MyVolumio to eu1 Dec 11 10:58:03 volumiokhv volumio[691]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 11 10:58:04 volumiokhv volumio[691]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 11 10:58:04 volumiokhv volumio[691]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 11 10:58:04 volumiokhv sudo[957]: pam_unix(sudo:session): session closed for user root Dec 11 10:58:04 volumiokhv autossh[960]: port set to 0, monitoring disabled Dec 11 10:58:04 volumiokhv autossh[960]: starting ssh (count 1) Dec 11 10:58:04 volumiokhv autossh[960]: ssh child pid is 963 Dec 11 10:58:04 volumiokhv volumio[691]: info: [MyVolumio PluginManager] Device activated, enabling myvolumio plugins... Dec 11 10:58:04 volumiokhv volumio[691]: info: MyVolumio status changed Dec 11 10:58:04 volumiokhv volumio[691]: info: Streaming services startup Dec 11 10:58:04 volumiokhv volumio[691]: info: Re-Starting Streaming Daemon Dec 11 10:58:04 volumiokhv volumio[691]: info: Remote SSH Started Dec 11 10:58:04 volumiokhv volumio[691]: info: Setting Geolocation for MyVolumio to eu1 Dec 11 10:58:04 volumiokhv volumio[691]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 11 10:58:04 volumiokhv volumio-streaming-daemon[936]: Restarting daemon Dec 11 10:58:04 volumiokhv volumio-streaming-daemon[936]: Environment: production Dec 11 10:58:05 volumiokhv volumio[691]: info: Updating MyVolumio device info Dec 11 10:58:05 volumiokhv volumio[691]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 11 10:58:05 volumiokhv volumio[691]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 11 10:58:05 volumiokhv volumio-streaming-daemon[936]: Environment: production Dec 11 10:58:05 volumiokhv volumio[691]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 11 10:58:05 volumiokhv volumio[691]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getHwuuid Dec 11 10:58:05 volumiokhv volumio[691]: info: [MyVolumio PluginManager] Loading plugin "bluetooth"... Dec 11 10:58:05 volumiokhv volumio-streaming-daemon[936]: Enabled services: Dec 11 10:58:05 volumiokhv volumio-streaming-daemon[936]: 1) tidal Dec 11 10:58:05 volumiokhv volumio-streaming-daemon[936]: 2) qobuz Dec 11 10:58:05 volumiokhv volumio[691]: info: [1639220285675] Starting BluetoothController Dec 11 10:58:05 volumiokhv volumio[691]: info: [MyVolumio PluginManager] Loading plugin "cd_controller"... Dec 11 10:58:06 volumiokhv volumio[691]: info: [MyVolumio PluginManager] Loading plugin "tidal"... Dec 11 10:58:06 volumiokhv volumio[691]: info: [MyVolumio PluginManager] Loading plugin "qobuz"... Dec 11 10:58:06 volumiokhv volumio[691]: info: [MyVolumio PluginManager] Loading plugin "tidalconnect"... Dec 11 10:58:06 volumiokhv volumio[691]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 27 Dec 11 10:58:06 volumiokhv volumio[691]: info: Updating MyVolumio device info Dec 11 10:58:06 volumiokhv volumio[691]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 11 10:58:06 volumiokhv volumio[691]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 11 10:58:06 volumiokhv volumio[691]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 11 10:58:06 volumiokhv volumio[691]: info: Fetching Streaming Services browse cache Dec 11 10:58:06 volumiokhv volumio[691]: Current plan: virtuoso. Enabling manifest UI Dec 11 10:58:06 volumiokhv volumio[691]: info: [MyVolumio PluginManager] Starting plugin audio_interface.bluetooth Dec 11 10:58:06 volumiokhv volumio[691]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 11 10:58:06 volumiokhv volumio[691]: info: [MyVolumio PluginManager] Starting plugin music_service.cd_controller Dec 11 10:58:06 volumiokhv volumio[691]: info: [MyVolumio PluginManager] Plugin cd_controller disabled by user. Not starting Dec 11 10:58:06 volumiokhv volumio[691]: info: [MyVolumio PluginManager] Starting plugin music_service.tidal Dec 11 10:58:06 volumiokhv volumio[691]: info: Refreshing TIDAL token Dec 11 10:58:06 volumiokhv volumio[691]: info: [MyVolumio PluginManager] Starting plugin music_service.qobuz Dec 11 10:58:06 volumiokhv volumio[691]: info: Refreshing QOBUZ token Dec 11 10:58:06 volumiokhv volumio[691]: info: [MyVolumio PluginManager] Starting plugin music_service.tidalconnect Dec 11 10:58:06 volumiokhv sudo[975]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/hciconfig hci0 name VolumioKHV Dec 11 10:58:06 volumiokhv sudo[975]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 11 10:58:06 volumiokhv volumio[691]: info: CoreCommandRouter::volumioGetState Dec 11 10:58:06 volumiokhv volumio[691]: info: CorePlayQueue::getTrack 0 Dec 11 10:58:06 volumiokhv sudo[979]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 11 10:58:06 volumiokhv sudo[979]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 11 10:58:06 volumiokhv sudo[975]: pam_unix(sudo:session): session closed for user root Dec 11 10:58:07 volumiokhv kernel: Bluetooth: Core ver 2.22 Dec 11 10:58:07 volumiokhv kernel: NET: Registered protocol family 31 Dec 11 10:58:07 volumiokhv kernel: Bluetooth: HCI device and connection manager initialized Dec 11 10:58:07 volumiokhv kernel: Bluetooth: HCI socket layer initialized Dec 11 10:58:07 volumiokhv kernel: Bluetooth: L2CAP socket layer initialized Dec 11 10:58:07 volumiokhv kernel: Bluetooth: SCO socket layer initialized Dec 11 10:58:07 volumiokhv sudo[979]: pam_unix(sudo:session): session closed for user root Dec 11 10:58:07 volumiokhv volumio[691]: error: Cannot set new Bluetooth Name : Error: Command failed: /usr/bin/sudo /bin/hciconfig hci0 name 'VolumioKHV' Dec 11 10:58:07 volumiokhv volumio[691]: Can't get device info: No such device Dec 11 10:58:07 volumiokhv volumio-streaming-daemon[936]: Environment: production Dec 11 10:58:07 volumiokhv volumio-streaming-daemon[936]: Environment: production Dec 11 10:58:07 volumiokhv volumio[691]: info: Access Token successfully retrieved Dec 11 10:58:07 volumiokhv volumio[691]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 11 10:58:07 volumiokhv volumio[691]: info: [1639220287323] CoreMusicLibrary::Adding element QOBUZ Dec 11 10:58:07 volumiokhv volumio[691]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 11 10:58:07 volumiokhv volumio[691]: Cannot find translation for sourceSpotify Dec 11 10:58:07 volumiokhv volumio[691]: Cannot find translation for sourceQOBUZ Dec 11 10:58:07 volumiokhv volumio[691]: info: Stopping AccessToken refresher cron for QOBUZ Dec 11 10:58:07 volumiokhv volumio[691]: info: AccessToken refresher cron started for QOBUZ Dec 11 10:58:07 volumiokhv volumio[691]: info: Fetching Streaming Services browse cache Dec 11 10:58:07 volumiokhv volumio[691]: info: Access Token successfully retrieved Dec 11 10:58:08 volumiokhv volumio[691]: info: Successfully retrieved User Session From TIDAL Dec 11 10:58:08 volumiokhv volumio[691]: info: Successfully retrieved User Subscription From TIDAL Dec 11 10:58:08 volumiokhv volumio[691]: info: Adding TIDAL to Browse Sources Dec 11 10:58:08 volumiokhv volumio[691]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 11 10:58:08 volumiokhv volumio[691]: info: [1639220288404] CoreMusicLibrary::Adding element TIDAL Dec 11 10:58:08 volumiokhv volumio[691]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 11 10:58:08 volumiokhv volumio[691]: Cannot find translation for sourceSpotify Dec 11 10:58:08 volumiokhv volumio[691]: Cannot find translation for sourceQOBUZ Dec 11 10:58:08 volumiokhv volumio[691]: Cannot find translation for sourceTIDAL Dec 11 10:58:08 volumiokhv volumio[691]: info: Stopping AccessToken refresher cron Dec 11 10:58:08 volumiokhv volumio[691]: info: AccessToken refresher cron started Dec 11 10:58:09 volumiokhv sudo[989]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumiobt.service Dec 11 10:58:09 volumiokhv sudo[989]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 11 10:58:09 volumiokhv systemd[1]: Started Volumio Bluetooth Module. Dec 11 10:58:09 volumiokhv sudo[989]: pam_unix(sudo:session): session closed for user root Dec 11 10:58:10 volumiokhv sudo[992]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /var/lib/bluetooth/* Dec 11 10:58:10 volumiokhv sudo[992]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 11 10:58:10 volumiokhv volumiobt[991]: /bin/chmod: cannot access '/var/lib/bluetooth/*': No such file or directory Dec 11 10:58:10 volumiokhv sudo[992]: pam_unix(sudo:session): session closed for user root Dec 11 10:58:10 volumiokhv volumio[691]: info: Volumio BT Module successfully started Dec 11 10:58:10 volumiokhv volumio[691]: info: TidalConnect service stoped! Dec 11 10:58:10 volumiokhv volumio[691]: info: Adding tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Dec 11 10:58:10 volumiokhv volumio[691]: info: Adding tc_connect REST Endpoint for plugin: music_service/tidalconnect Dec 11 10:58:10 volumiokhv sudo[1000]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Dec 11 10:58:10 volumiokhv sudo[1000]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 11 10:58:10 volumiokhv systemd[1]: Started Volumio Tidal Connect Service. Dec 11 10:58:10 volumiokhv sudo[1000]: pam_unix(sudo:session): session closed for user root Dec 11 10:58:10 volumiokhv volumio[691]: info: Executing endpoint tc_getconfig Dec 11 10:58:10 volumiokhv volumio[691]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Dec 11 10:58:10 volumiokhv vtcs[1002]: STARTING TidalConnect services, version: 1.1.7.5 Dec 11 10:58:10 volumiokhv vtcs[1002]: STARTED TidalConnect services. Dec 11 10:58:10 volumiokhv volumio[691]: info: Executing endpoint tc_connect Dec 11 10:58:10 volumiokhv volumio[691]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Dec 11 10:58:10 volumiokhv volumio[691]: Connecting to TidalConnect. Dec 11 10:58:10 volumiokhv volumio[691]: info: CoreCommandRouter::servicePushState Dec 11 10:58:10 volumiokhv volumio[691]: info: CoreStateMachine::pushState Dec 11 10:58:10 volumiokhv volumio[691]: info: CorePlayQueue::getTrack 0 Dec 11 10:58:10 volumiokhv volumio[691]: info: CoreCommandRouter::volumioPushState Dec 11 10:58:11 volumiokhv volumio[691]: info: CorePlayQueue::getTrack 0 Dec 11 10:58:11 volumiokhv volumio[691]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current qobuz Received tidalconnect Dec 11 10:58:11 volumiokhv volumio[691]: info: CoreCommandRouter::servicePushState Dec 11 10:58:11 volumiokhv volumio[691]: info: CoreStateMachine::pushState Dec 11 10:58:11 volumiokhv volumio[691]: info: CorePlayQueue::getTrack 0 Dec 11 10:58:11 volumiokhv volumio[691]: info: CoreCommandRouter::volumioPushState Dec 11 10:58:11 volumiokhv volumio[691]: info: CorePlayQueue::getTrack 0 Dec 11 10:58:11 volumiokhv volumio[691]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current qobuz Received tidalconnect Dec 11 10:58:11 volumiokhv sudo[1013]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/killall pulseaudio Dec 11 10:58:11 volumiokhv sudo[1013]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 11 10:58:11 volumiokhv volumiobt[991]: pulseaudio: no process found Dec 11 10:58:11 volumiokhv sudo[1013]: pam_unix(sudo:session): session closed for user root Dec 11 10:58:11 volumiokhv dbus-daemon[399]: [system] Activating via systemd: service name='org.freedesktop.RealtimeKit1' unit='rtkit-daemon.service' requested by ':1.13' (uid=1000 pid=1024 comm="/usr/bin/pulseaudio ") Dec 11 10:58:11 volumiokhv systemd[1]: Starting RealtimeKit Scheduling Policy Service... Dec 11 10:58:11 volumiokhv volumio[691]: ------------------------------------ BT MESSAGE: BT STATUS: running Dec 11 10:58:11 volumiokhv volumio[691]: BT PLUGIN MESSAGE: Playing: false Dec 11 10:58:11 volumiokhv volumio[691]: BT PLUGIN MESSAGE: VOLATILE: undefined Dec 11 10:58:11 volumiokhv volumio[691]: BT PLUGIN MESSAGE: STATE:undefined Dec 11 10:58:11 volumiokhv dbus-daemon[399]: [system] Successfully activated service 'org.freedesktop.RealtimeKit1' Dec 11 10:58:11 volumiokhv systemd[1]: Started RealtimeKit Scheduling Policy Service. Dec 11 10:58:11 volumiokhv rtkit-daemon[1025]: Successfully called chroot. Dec 11 10:58:11 volumiokhv rtkit-daemon[1025]: Successfully dropped privileges. Dec 11 10:58:11 volumiokhv rtkit-daemon[1025]: Successfully limited resources. Dec 11 10:58:11 volumiokhv rtkit-daemon[1025]: Running. Dec 11 10:58:11 volumiokhv rtkit-daemon[1025]: Canary thread running. Dec 11 10:58:11 volumiokhv rtkit-daemon[1025]: Watchdog thread running. Dec 11 10:58:11 volumiokhv dbus-daemon[399]: [system] Activating via systemd: service name='org.freedesktop.PolicyKit1' unit='polkit.service' requested by ':1.14' (uid=0 pid=1025 comm="/usr/lib/rtkit/rtkit-daemon ") Dec 11 10:58:11 volumiokhv systemd[1]: Starting Authorization Manager... Dec 11 10:58:11 volumiokhv volumio[691]: ------------------------------------ BT MESSAGE: BT STATUS: running Dec 11 10:58:11 volumiokhv volumio[691]: BT PLUGIN MESSAGE: Playing: false Dec 11 10:58:11 volumiokhv volumio[691]: BT PLUGIN MESSAGE: VOLATILE: undefined Dec 11 10:58:11 volumiokhv volumio[691]: BT PLUGIN MESSAGE: STATE:undefined Dec 11 10:58:11 volumiokhv polkitd[1028]: started daemon version 0.105 using authority implementation `local' version `0.105' Dec 11 10:58:11 volumiokhv dbus-daemon[399]: [system] Successfully activated service 'org.freedesktop.PolicyKit1' Dec 11 10:58:11 volumiokhv systemd[1]: Started Authorization Manager. Dec 11 10:58:11 volumiokhv ntpd[546]: Soliciting pool server 212.186.223.161 Dec 11 10:58:12 volumiokhv dbus-daemon[399]: [system] Activating via systemd: service name='org.bluez' unit='dbus-org.bluez.service' requested by ':1.34' (uid=1000 pid=1024 comm="/usr/bin/pulseaudio ") Dec 11 10:58:12 volumiokhv systemd[1]: Starting Bluetooth service... Dec 11 10:58:12 volumiokhv volumio[691]: ------------------------------------ BT MESSAGE: BT STATUS: running Dec 11 10:58:12 volumiokhv volumio[691]: BT PLUGIN MESSAGE: Playing: false Dec 11 10:58:12 volumiokhv volumio[691]: BT PLUGIN MESSAGE: VOLATILE: undefined Dec 11 10:58:12 volumiokhv volumio[691]: BT PLUGIN MESSAGE: STATE:undefined Dec 11 10:58:12 volumiokhv pulseaudio[1024]: Unable to contact D-Bus: org.freedesktop.DBus.Error.NotSupported: Unable to autolaunch a dbus-daemon without a $DISPLAY for X11 Dec 11 10:58:12 volumiokhv pulseaudio[1024]: Unable to contact D-Bus: org.freedesktop.DBus.Error.NotSupported: Unable to autolaunch a dbus-daemon without a $DISPLAY for X11 Dec 11 10:58:12 volumiokhv volumiobt[991]: Applying permissions Dec 11 10:58:12 volumiokhv volumiobt[991]: Setting BT discoverable and pairable Dec 11 10:58:12 volumiokhv bluetoothd[1033]: Bluetooth daemon 5.50 Dec 11 10:58:12 volumiokhv bluetoothd[1033]: Unknown key AutoConnectTimeout for group General in /etc/bluetooth/main.conf Dec 11 10:58:12 volumiokhv dbus-daemon[399]: [system] Successfully activated service 'org.bluez' Dec 11 10:58:12 volumiokhv systemd[1]: Started Bluetooth service. Dec 11 10:58:12 volumiokhv bluetoothd[1033]: Starting SDP server Dec 11 10:58:12 volumiokhv bluetoothd[1033]: Excluding (cli) sap Dec 11 10:58:12 volumiokhv kernel: Bluetooth: BNEP (Ethernet Emulation) ver 1.3 Dec 11 10:58:12 volumiokhv kernel: Bluetooth: BNEP filters: protocol multicast Dec 11 10:58:12 volumiokhv kernel: Bluetooth: BNEP socket layer initialized Dec 11 10:58:12 volumiokhv dbus-daemon[399]: [system] Activating via systemd: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service' requested by ':1.35' (uid=0 pid=1033 comm="/usr/lib/bluetooth/bluetoothd --noplugin=sap ") Dec 11 10:58:12 volumiokhv bluetoothd[1033]: Bluetooth management interface 1.14 initialized Dec 11 10:58:12 volumiokhv volumiobt[991]: Agent registered Dec 11 10:58:12 volumiokhv volumiobt[991]: [bluetooth]# -e power on Dec 11 10:58:12 volumiokhv volumiobt[991]: Invalid command in menu main: -e Dec 11 10:58:12 volumiokhv volumiobt[991]: Dec 11 10:58:12 volumiokhv volumiobt[991]: Use "help" for a list of available commands in a menu. Dec 11 10:58:12 volumiokhv volumiobt[991]: Use "menu " if you want to enter any submenu. Dec 11 10:58:12 volumiokhv volumiobt[991]: Use "back" if you want to return to menu main. Dec 11 10:58:12 volumiokhv volumiobt[991]: [bluetooth]# agent on Dec 11 10:58:12 volumiokhv volumiobt[991]: Agent is already registered Dec 11 10:58:12 volumiokhv volumiobt[991]: [bluetooth]# discoverable on Dec 11 10:58:12 volumiokhv volumiobt[991]: No default controller available Dec 11 10:58:12 volumiokhv volumiobt[991]: [bluetooth]# pairable on Dec 11 10:58:12 volumiokhv volumiobt[991]: No default controller available Dec 11 10:58:12 volumiokhv volumiobt[991]: [bluetooth]# agent NoInputNoOutput Dec 11 10:58:12 volumiokhv volumiobt[991]: Agent is already registered Dec 11 10:58:12 volumiokhv volumiobt[991]: [bluetooth]# default-agent Dec 11 10:58:12 volumiokhv volumiobt[991]: [bluetooth]# quit Dec 11 10:58:12 volumiokhv pulseaudio[1024]: 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 Dec 11 10:58:12 volumiokhv volumiobt[991]: [59B blob data] Dec 11 10:58:12 volumiokhv systemd[1]: Starting Hostname Service... Dec 11 10:58:12 volumiokhv volumio[691]: ------------------------------------ BT MESSAGE: BT STATUS: running Dec 11 10:58:12 volumiokhv volumio[691]: BT PLUGIN MESSAGE: Playing: false Dec 11 10:58:12 volumiokhv volumio[691]: BT PLUGIN MESSAGE: VOLATILE: undefined Dec 11 10:58:12 volumiokhv volumio[691]: BT PLUGIN MESSAGE: STATE:undefined Dec 11 10:58:12 volumiokhv ntpd[546]: Soliciting pool server 217.196.145.42 Dec 11 10:58:12 volumiokhv dbus-daemon[399]: [system] Successfully activated service 'org.freedesktop.hostname1' Dec 11 10:58:12 volumiokhv systemd[1]: Started Hostname Service. Dec 11 10:58:12 volumiokhv ntpd[546]: Soliciting pool server 144.76.197.108 Dec 11 10:58:12 volumiokhv ntpd[546]: Soliciting pool server 178.251.64.52 Dec 11 10:58:13 volumiokhv volumio[691]: info: TidalConnect service started! Dec 11 10:58:13 volumiokhv volumio[691]: info: BOOT COMPLETED Dec 11 10:58:13 volumiokhv volumio[691]: [Metrics] CommandRouter: 47s 488.40ms Dec 11 10:58:13 volumiokhv volumio[691]: info: CoreCommandRouter::volumiosetStartupVolume Dec 11 10:58:13 volumiokhv volumio[691]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 10:58:13 volumiokhv volumio[691]: info: VolumeController:: Setting startup Volume 20 Dec 11 10:58:13 volumiokhv volumio[691]: info: CoreCommandRouter::volumioGetState Dec 11 10:58:13 volumiokhv volumio[691]: info: CorePlayQueue::getTrack 0 Dec 11 10:58:13 volumiokhv volumio[691]: info: VolumeController::SetAlsaVolume20 Dec 11 10:58:13 volumiokhv volumio[691]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 11 10:58:13 volumiokhv volumio[691]: info: CoreCommandRouter::Close All Modals sent Dec 11 10:58:13 volumiokhv volumio[691]: info: CoreCommandRouter::Close All Modals sent Dec 11 10:58:13 volumiokhv volumio[691]: info: CoreStateMachine::pushState Dec 11 10:58:13 volumiokhv volumio[691]: info: CorePlayQueue::getTrack 0 Dec 11 10:58:13 volumiokhv volumio[691]: info: CoreCommandRouter::volumioPushState Dec 11 10:58:13 volumiokhv kernel: pcm512x 1-004c: No SCLK, using BCLK: -2 Dec 11 10:58:13 volumiokhv ntpd[546]: Soliciting pool server 86.59.80.170 Dec 11 10:58:13 volumiokhv ntpd[546]: Soliciting pool server 185.242.177.6 Dec 11 10:58:13 volumiokhv ntpd[546]: Soliciting pool server 86.59.113.124 Dec 11 10:58:14 volumiokhv volumio[691]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 11 10:58:14 volumiokhv volumio[691]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 11 10:58:14 volumiokhv volumio[691]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Dec 11 10:58:14 volumiokhv volumio[691]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 11 10:58:14 volumiokhv volumio[691]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Dec 11 10:58:14 volumiokhv ntpd[546]: Soliciting pool server 91.206.8.34 Dec 11 10:58:14 volumiokhv ntpd[546]: Soliciting pool server 94.199.173.123 Dec 11 10:58:14 volumiokhv ntpd[546]: Soliciting pool server 91.206.8.34 Dec 11 10:58:14 volumiokhv ntpd[546]: Soliciting pool server 206.108.0.133 Dec 11 10:58:15 volumiokhv ntpd[546]: Soliciting pool server 168.96.251.197 Dec 11 10:58:15 volumiokhv ntpd[546]: Soliciting pool server 85.237.30.84 Dec 11 10:58:16 volumiokhv ntpd[546]: Soliciting pool server 2001:858:2:4:887d:c7ff:fef8:d02d Dec 11 10:58:40 volumiokhv ntpd[546]: receive: Unexpected origin timestamp 0xe55f02cb.ae5b16c3 does not match aorg 0000000000.00000000 from server@217.196.145.42 xmt 0xe55f02e0.06506f1b Dec 11 10:58:40 volumiokhv ntpd[546]: receive: Unexpected origin timestamp 0xe55f02cb.ae5583f8 does not match aorg 0000000000.00000000 from server@144.76.197.108 xmt 0xe55f02e0.068a1641 Dec 11 10:58:40 volumiokhv ntpd[546]: receive: Unexpected origin timestamp 0xe55f02cb.ae37b314 does not match aorg 0000000000.00000000 from server@206.108.0.133 xmt 0xe55f02e0.132946ce Dec 11 10:58:46 volumiokhv volumiossh-tunnel[959]: Warning: Permanently added '[eu1.myvolumio.org]:2222,[188.166.162.121]:2222' (RSA) to the list of known hosts. Dec 11 10:58:55 volumiokhv volumio[691]: info: CALLMETHOD: system_controller system sendBugReport [object Object] Dec 11 10:58:55 volumiokhv volumio[691]: info: CoreCommandRouter::executeOnPlugin: system , sendBugReport Dec 11 10:58:56 volumiokhv sudo[1062]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig Dec 11 10:58:56 volumiokhv sudo[1062]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 11 10:58:56 volumiokhv sudo[1062]: pam_unix(sudo:session): session closed for user root Dec 11 10:59:03 volumiokhv systemd[1]: systemd-hostnamed.service: Succeeded. Dec 11 10:59:09 volumiokhv sudo[1108]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl -p 7 Dec 11 10:59:09 volumiokhv sudo[1108]: pam_unix(sudo:session): session opened for user root by (uid=0)